[ 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 438349050 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003113] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.005014] kvm-guest: setup PV IPIs [ 0.008749] ..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.009021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010013] pid_max: default: 32768 minimum: 301 [ 0.011133] LSM: Security Framework initializing [ 0.012049] Yama: becoming mindful. [ 0.013085] SELinux: Initializing. [ 0.014082] *** VALIDATE selinux *** [ 0.023047] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028152] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029153] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030109] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031110] *** VALIDATE tmpfs *** [ 0.032449] *** VALIDATE proc *** [ 0.034151] *** VALIDATE cgroup *** [ 0.035010] *** VALIDATE cgroup2 *** [ 0.037079] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039061] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.042023] Spectre V2 : User space: Vulnerable [ 0.043012] Speculative Store Bypass: Vulnerable [ 0.046601] debug: unmapping init [mem 0xffffffff94459000-0xffffffff94460fff] [ 0.049248] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.050791] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.051039] ... version: 2 [ 0.052020] ... bit width: 48 [ 0.053019] ... generic registers: 4 [ 0.054018] ... value mask: 0000ffffffffffff [ 0.055022] ... max period: 00007fffffffffff [ 0.056025] ... fixed-purpose events: 3 [ 0.057020] ... event mask: 000000070000000f [ 0.059293] rcu: Hierarchical SRCU implementation. [ 0.062058] smp: Bringing up secondary CPUs ... [ 0.063721] x86: Booting SMP configuration: [ 0.064053] .... node #0, CPUs: #1 #2 #3 [ 0.067595] smp: Brought up 1 node, 4 CPUs [ 0.069022] smpboot: Max logical packages: 1 [ 0.070030] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.214131] node 0 deferred pages initialised in 142ms [ 0.217548] devtmpfs: initialized [ 0.218197] x86/mm: Memory block size: 128MB [ 0.221576] gcov: version magic: 0x41383552 [ 0.223261] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.226089] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.229328] pinctrl core: initialized pinctrl subsystem [ 0.231203] [ 0.231651] ************************************************************* [ 0.234112] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.236011] ** ** [ 0.239013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.241013] ** ** [ 0.243009] ** This means that this kernel is built to expose internal ** [ 0.245012] ** IOMMU data structures, which may compromise security on ** [ 0.248015] ** your system. ** [ 0.252015] ** ** [ 0.254011] ** If you see this message and you are not debugging the ** [ 0.256008] ** kernel, report this immediately to your vendor! ** [ 0.257011] ** ** [ 0.260012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.262008] ************************************************************* [ 0.264662] NET: Registered protocol family 16 [ 0.266359] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.268053] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.271058] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.275029] cpuidle: using governor menu [ 0.276810] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.279525] PCI: Using configuration type 1 for base access [ 0.281127] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.289107] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.292047] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.296096] cryptd: max_cpu_qlen set to 1000 [ 0.299360] ACPI: Added _OSI(Module Device) [ 0.301012] ACPI: Added _OSI(Processor Device) [ 0.302015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.304012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.309319] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.315677] ACPI: Interpreter enabled [ 0.317089] ACPI: PM: (supports S0 S3 S4 S5) [ 0.319022] ACPI: Using IOAPIC for interrupt routing [ 0.321166] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.325435] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.336837] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.339066] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.342032] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.344093] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.350759] acpiphp: Slot [2] registered [ 0.352270] acpiphp: Slot [5] registered [ 0.354289] acpiphp: Slot [6] registered [ 0.355162] acpiphp: Slot [3] registered [ 0.357142] acpiphp: Slot [4] registered [ 0.358160] acpiphp: Slot [7] registered [ 0.360120] acpiphp: Slot [8] registered [ 0.362143] acpiphp: Slot [9] registered [ 0.363105] acpiphp: Slot [10] registered [ 0.365112] acpiphp: Slot [11] registered [ 0.366103] acpiphp: Slot [12] registered [ 0.368166] acpiphp: Slot [13] registered [ 0.370125] acpiphp: Slot [14] registered [ 0.372124] acpiphp: Slot [15] registered [ 0.373113] acpiphp: Slot [16] registered [ 0.375114] acpiphp: Slot [17] registered [ 0.376164] acpiphp: Slot [18] registered [ 0.378107] acpiphp: Slot [19] registered [ 0.379104] acpiphp: Slot [20] registered [ 0.381110] acpiphp: Slot [21] registered [ 0.382134] acpiphp: Slot [22] registered [ 0.384109] acpiphp: Slot [23] registered [ 0.386167] acpiphp: Slot [24] registered [ 0.387128] acpiphp: Slot [25] registered [ 0.389114] acpiphp: Slot [26] registered [ 0.390108] acpiphp: Slot [27] registered [ 0.392146] acpiphp: Slot [28] registered [ 0.394130] acpiphp: Slot [29] registered [ 0.396123] acpiphp: Slot [30] registered [ 0.397113] acpiphp: Slot [31] registered [ 0.399083] PCI host bridge to bus 0000:00 [ 0.400017] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.403026] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.405083] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.407027] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.410025] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.413087] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.415179] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.419068] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.422496] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.429014] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.433919] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.436017] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.438019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.441023] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.444284] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.446881] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.449043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.452930] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.457012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.467016] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.472011] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.478674] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.484014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.489014] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.512014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.521968] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.529014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.539109] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.560013] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.567527] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.570419] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.573384] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.576440] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.578231] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.584164] iommu: Default domain type: Passthrough [ 0.585459] SCSI subsystem initialized [ 0.586196] ACPI: bus type USB registered [ 0.587128] usbcore: registered new interface driver usbfs [ 0.589105] usbcore: registered new interface driver hub [ 0.591078] usbcore: registered new device driver usb [ 0.593237] pps_core: LinuxPPS API ver. 1 registered [ 0.595013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.599064] PTP clock support registered [ 0.601082] EDAC MC: Ver: 3.0.0 [ 0.603147] PCI: Using ACPI for IRQ routing [ 0.605806] NetLabel: Initializing [ 0.607010] NetLabel: domain hash size = 128 [ 0.607994] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.609079] NetLabel: unlabeled traffic allowed by default [ 0.611134] vgaarb: loaded [ 0.612256] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.614014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.619233] clocksource: Switched to clocksource kvm-clock [ 0.718889] VFS: Disk quotas dquot_6.6.0 [ 0.720712] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.723490] *** VALIDATE ramfs *** [ 0.724468] *** VALIDATE hugetlbfs *** [ 0.725928] pnp: PnP ACPI init [ 0.727545] pnp: PnP ACPI: found 6 devices [ 0.745223] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.748266] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.750795] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.752872] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.755738] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.758593] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.761686] NET: Registered protocol family 2 [ 0.764251] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.769417] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.771929] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.777243] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.780411] TCP: Hash tables configured (established 65536 bind 65536) [ 0.783469] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.786756] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.789264] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.791188] NET: Registered protocol family 1 [ 0.793862] RPC: Registered named UNIX socket transport module. [ 0.795989] RPC: Registered udp transport module. [ 0.797308] RPC: Registered tcp transport module. [ 0.799172] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.801519] NET: Registered protocol family 44 [ 0.802823] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.804446] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.806633] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.808825] PCI: CLS 0 bytes, default 64 [ 0.810868] Unpacking initramfs... [ 2.225262] debug: unmapping init [mem 0xffff9f627cc64000-0xffff9f627ffcffff] [ 2.229765] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.231764] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.235034] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.749150] Initialise system trusted keyrings [ 2.750885] Key type blacklist registered [ 2.753231] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.761776] zbud: loaded [ 2.764973] *** VALIDATE nfs *** [ 2.766374] *** VALIDATE nfs4 *** [ 2.768145] pstore: using deflate compression [ 2.771820] Platform Keyring initialized [ 2.881244] NET: Registered protocol family 38 [ 2.883535] Key type asymmetric registered [ 2.885231] Asymmetric key parser 'x509' registered [ 2.887470] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.891208] io scheduler mq-deadline registered [ 2.893100] io scheduler kyber registered [ 2.894731] io scheduler bfq registered [ 2.898107] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.901727] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.904945] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.907586] ACPI: Power Button [PWRF] [ 2.913201] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.919989] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.930851] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.957915] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.987017] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.992656] Non-volatile memory driver v1.3 [ 2.994725] Linux agpgart interface v0.103 [ 3.034423] virtio_blk virtio1: [vda] 146616 512-byte logical blocks (75.1 MB/71.6 MiB) [ 3.037452] vda: detected capacity change from 0 to 75067392 [ 3.053880] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.057150] vdb: detected capacity change from 0 to 1073741824 [ 3.065299] libphy: Fixed MDIO Bus: probed [ 3.077723] usbcore: registered new interface driver usbserial_generic [ 3.080371] usbserial: USB Serial support registered for generic [ 3.082617] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.087527] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.089671] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.092430] mousedev: PS/2 mouse device common for all mice [ 3.095666] rtc_cmos 00:05: RTC can wake from S4 [ 3.101594] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.102431] rtc_cmos 00:05: registered as rtc0 [ 3.108900] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.110949] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.112895] intel_pstate: CPU model not supported [ 3.117850] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.122224] hid: raw HID events driver (C) Jiri Kosina [ 3.124716] usbcore: registered new interface driver usbhid [ 3.127084] usbhid: USB HID core driver [ 3.128930] drop_monitor: Initializing network drop monitor service [ 3.131538] Initializing XFRM netlink socket [ 3.133659] NET: Registered protocol family 10 [ 3.137486] Segment Routing with IPv6 [ 3.139825] NET: Registered protocol family 17 [ 3.142944] mpls_gso: MPLS GSO support [ 3.149240] RAS: Correctable Errors collector initialized. [ 3.151917] AVX version of gcm_enc/dec engaged. [ 3.153511] AES CTR mode by8 optimization enabled [ 3.231230] sched_clock: Marking stable (3231031067, 0)->(4115819379, -884788312) [ 3.235553] registered taskstats version 1 [ 3.237862] Loading compiled-in X.509 certificates [ 3.240427] zswap: loaded using pool lzo/zbud [ 3.267642] Key type big_key registered [ 3.280732] Key type encrypted registered [ 3.283173] ima: No TPM chip found, activating TPM-bypass! [ 3.286527] ima: Allocated hash algorithm: sha1 [ 3.288514] ima: No architecture policies found [ 3.290617] evm: Initialising EVM extended attributes: [ 3.292741] evm: security.selinux [ 3.294382] evm: security.ima [ 3.295560] evm: security.capability [ 3.297270] evm: HMAC attrs: 0x1 [ 3.300156] rtc_cmos 00:05: setting system clock to 2026-08-26 22:45:39 UTC (1787784339) [ 3.308068] debug: unmapping init [mem 0xffffffff95403000-0xffffffff955fffff] [ 3.311986] debug: unmapping init [mem 0xffffffff94182000-0xffffffff94458fff] [ 3.320186] Write protecting the kernel read-only data: 28672k [ 3.324041] debug: unmapping init [mem 0xffffffff92803000-0xffffffff929fffff] [ 3.327352] debug: unmapping init [mem 0xffffffff93114000-0xffffffff931fffff] [ 3.362245] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.372857] systemd[1]: Detected virtualization kvm. [ 3.375173] systemd[1]: Detected architecture x86-64. [ 3.377258] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.402763] systemd[1]: No hostname configured. [ 3.404935] systemd[1]: Set hostname to . [ 3.407588] random: systemd: uninitialized urandom read (16 bytes read) [ 3.410236] systemd[1]: Initializing machine ID from random generator. [ 3.554105] random: systemd: uninitialized urandom read (16 bytes read) [ 3.557527] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.563646] random: systemd: uninitialized urandom read (16 bytes read) [ 3.571453] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ 3.582590] systemd[1]: Started Memstrack Anylazing Service. [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Swap. [ OK ] Reached target Slices. [ OK ] Reached target Sockets. Starting Journal Service... Starting Setup Virtual Console... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. 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... [ 4.239448] device-mapper: uevent: version 1.0.3 [ 4.241876] 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 ] [ 4.565294] random: fast init done Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.042542] virtio_net virtio0 ens2: renamed from eth0 [ 5.082707] scsi host0: ata_piix [ 5.101852] scsi host1: ata_piix [ 5.107184] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.111091] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.673198] dracut-initqueue[581]: RTNETLINK answers: File exists [ 9.803602] random: crng init done [ 9.805052] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.158949] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Timers. [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.248882] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.494218] SELinux: Disabled at runtime. [ 11.559389] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.568761] systemd[1]: Detected virtualization kvm. [ 11.570929] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.059594] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.063874] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.068627] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.072778] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.076300] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.085908] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.089781] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. [ OK ] Listening on Process Core Dump Socket. [FAILED[ 12.127228] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS ] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Remount Root and Kernel File Systems... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Mounting Kernel Debug File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... Mounting Huge Pages File System... Starting Apply Kernel Variables... [ OK ] Created slice system-getty.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.543160] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.806446] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.825230] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.931244] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.945458] EDAC sbridge: Ver: 1.1.2 [ 13.834331] Key type dns_resolver registered [ 14.135602] NFS: Registering the id_resolver key type [ 14.138655] Key type id_resolver registered [ 14.140431] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. Starting Login Service... [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg307-client login: [ 41.939834] libcfs: loading out-of-tree module taints kernel. [ 42.015964] Key type ._llcrypt registered [ 42.017784] Key type .llcrypt registered [ 42.356353] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 42.363497] alg: No test for adler32 (adler32-zlib) [ 43.414712] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 43.931269] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 45.672225] Key type lgssc registered [ 47.153908] Lustre: Echo OBD driver; http://www.lustre.org/ [ 215.068571] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 219.614503] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 230.664172] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing check_logdir /tmp/testlogs/ [ 234.398661] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing yml_node [ 238.060971] Lustre: DEBUG MARKER: Client: 2.17.57.54 [ 240.238863] Lustre: DEBUG MARKER: MDS: 2.17.57.54 [ 240.608280] Lustre: lustre-OST0000-osc-ffff9f62e0162800: disconnect after 23s idle [ 242.315552] Lustre: DEBUG MARKER: OSS: 2.17.57.54 [ 243.972575] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Wed Aug 26 18:49:38 EDT 2026 [ 261.988637] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 263.297743] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 264.751946] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 275.083139] Lustre: Unmounted lustre-client [ 379.184719] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 384.407566] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 406.516149] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 452.022197] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 18:53:06 (1787784786) [ 481.162184] hrtimer: interrupt took 3962005 ns [ 503.554567] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 508.058627] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 543.268343] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 600.618873] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 606.048675] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 648.798584] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 671.651037] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 18:56:46 (1787785006) [ 773.549985] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 778.626591] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 804.076991] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 804.732956] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 826.066846] Lustre: Unmounted lustre-client [ 864.551780] Key type lgssc unregistered [ 864.884613] LNet: 21858:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 864.894240] LNetError: 21858:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 864.945077] LNet: Removed LNI 192.168.203.7@tcp [ 865.820153] Key type .llcrypt unregistered [ 865.824903] Key type ._llcrypt unregistered [ 882.862499] Key type ._llcrypt registered [ 882.864116] Key type .llcrypt registered [ 884.364773] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 19:00:18 (1787785218) [ 887.120309] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 887.132815] alg: No test for adler32 (adler32-zlib) [ 888.242695] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 888.459769] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 890.120196] Key type lgssc registered [ 891.647549] Lustre: Echo OBD driver; http://www.lustre.org/ [ 938.826484] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 944.588952] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 967.676693] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 970.414457] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 985.883980] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 19:02:00 (1787785320) [ 997.146686] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 19:02:11 (1787785331) [ 1005.814265] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 19:02:20 (1787785340) [ 1016.835391] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 19:02:31 (1787785351) [ 1039.079250] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 19:02:53 (1787785373) [ 1054.818536] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 19:03:09 (1787785389) [ 1072.039448] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 19:03:26 (1787785406) [ 1081.856473] Lustre: Unmounted lustre-client [ 1082.312541] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 1084.505652] Lustre: Unmounted lustre-client [ 1111.043842] Key type lgssc unregistered [ 1111.286875] LNet: 30244:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1111.294281] LNetError: 30244:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1111.311459] LNet: Removed LNI 192.168.203.7@tcp [ 1111.893186] Key type .llcrypt unregistered [ 1111.898733] Key type ._llcrypt unregistered [ 1141.821507] Key type ._llcrypt registered [ 1141.825290] Key type .llcrypt registered [ 1142.252565] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1142.261440] alg: No test for adler32 (adler32-zlib) [ 1143.501862] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 1143.693552] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 1145.370807] Key type lgssc registered [ 1146.720855] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1236.662959] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1241.900090] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1264.853730] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1317.615781] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 19:07:32 (1787785652) [ 1351.457431] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1357.027889] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1377.878455] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1379.586033] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 1507.983376] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 19:10:42 (1787785842) [ 1548.008502] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 19:11:22 (1787785882) [ 1558.369525] Lustre: Unmounted lustre-client [ 1636.181307] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1641.455951] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1662.238308] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1662.927870] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 1668.693104] Lustre: Unmounted lustre-client [ 1703.650164] Key type lgssc unregistered [ 1703.892753] LNet: 41578:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1703.899317] LNetError: 41578:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1703.916235] LNet: Removed LNI 192.168.203.7@tcp [ 1704.429196] Key type .llcrypt unregistered [ 1704.434237] Key type ._llcrypt unregistered [ 1718.809435] Key type ._llcrypt registered [ 1718.813540] Key type .llcrypt registered [ 1719.855311] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 19:14:14 (1787786054) [ 1722.024917] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1722.051328] alg: No test for adler32 (adler32-zlib) [ 1723.078100] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 1723.311079] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 1725.024176] Key type lgssc registered [ 1726.148850] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1772.996612] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1778.248513] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1797.117277] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1799.866714] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 1810.844613] Lustre: Unmounted lustre-client [ 1885.361843] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1890.706107] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1911.395380] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1912.232950] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 1918.486044] Lustre: Unmounted lustre-client [ 1945.659878] Key type lgssc unregistered [ 1945.895660] LNet: 47117:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1945.902342] LNetError: 47117:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1945.935343] LNet: Removed LNI 192.168.203.7@tcp [ 1946.549820] Key type .llcrypt unregistered [ 1946.551866] Key type ._llcrypt unregistered [ 1959.911269] Key type ._llcrypt registered [ 1959.913576] Key type .llcrypt registered [ 1960.966527] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 19:18:15 (1787786295) [ 1963.375856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1963.393533] alg: No test for adler32 (adler32-zlib) [ 1964.516437] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 1964.743239] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 1966.400588] Key type lgssc registered [ 1967.631870] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2015.074540] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2019.780269] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2037.903621] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2040.752244] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 2130.018402] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 19:21:05 (1787786465) [ 2131.280567] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2132.798198] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 19:21:07 (1787786467) [ 2134.651198] Lustre: Unmounted lustre-client [ 2159.548644] Key type lgssc unregistered [ 2159.760087] LNet: 52455:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2159.765722] LNetError: 52455:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2159.783957] LNet: Removed LNI 192.168.203.7@tcp [ 2160.476301] Key type .llcrypt unregistered [ 2160.480786] Key type ._llcrypt unregistered [ 2185.923373] Key type ._llcrypt registered [ 2185.928430] Key type .llcrypt registered [ 2186.265429] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2186.282856] alg: No test for adler32 (adler32-zlib) [ 2187.348425] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 2187.578319] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 2189.264509] Key type lgssc registered [ 2190.375845] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2219.443663] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 19:22:34 (1787786554) [ 2230.367556] Key type lgssc unregistered [ 2230.564955] LNet: 54868:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2230.573954] LNetError: 54868:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2230.607906] LNet: Removed LNI 192.168.203.7@tcp [ 2231.144217] Key type .llcrypt unregistered [ 2231.146252] Key type ._llcrypt unregistered [ 2240.974432] Key type ._llcrypt registered [ 2240.977219] Key type .llcrypt registered [ 2241.446157] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2241.457768] alg: No test for adler32 (adler32-zlib) [ 2242.557492] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 2242.829270] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 2244.528174] Key type lgssc registered [ 2245.899674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2293.013066] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2300.022756] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2321.175870] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2322.771437] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 2371.592240] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 19:25:06 (1787786706) [ 2373.124703] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2374.907263] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 19:25:09 (1787786709) [ 2376.699336] Lustre: Unmounted lustre-client [ 2445.347719] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2449.439192] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2520.039943] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 19:27:34 (1787786854) [ 2601.591519] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 2607.412941] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2621.934296] Lustre: lustre-MDT0001-mdc-ffff9f62c4c73800: Connection to lustre-MDT0001 (at 192.168.203.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2627.045955] Lustre: lustre-OST0000-osc-ffff9f62c4c73800: disconnect after 23s idle [ 2635.550245] LustreError: 63557:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.203.107@tcp: cfg command failed: rc = -17 [ 2635.563370] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2635.563370] [ 2635.567732] LustreError: 62817:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2650.522868] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 19:29:45 (1787786985) [ 2707.646616] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2789.287911] Lustre: Unmounted lustre-client [ 2942.212764] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 2947.077889] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2997.802235] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 19:35:32 (1787787332) [ 2999.298484] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3022.409980] Lustre: Unmounted lustre-client [ 3150.736905] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 19:38:05 (1787787485) [ 3152.154847] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3153.718422] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 19:38:08 (1787787488) [ 3235.992653] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 3242.472840] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3858.123306] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 19:49:53 (1787788193) [ 3859.614635] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3859.619906] Lustre: Skipped 3 previous similar messages [ 3869.930304] Lustre: Unmounted lustre-client [ 3990.426667] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3994.785208] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4013.177289] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4013.585715] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 5590.820807] Lustre: 95145:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9f62e0166000: catlog [0xa:0x5:0x0] crosses index zero [ 5613.116971] Lustre: Unmounted lustre-client [ 5638.137927] Key type lgssc unregistered [ 5638.392977] LNet: 103295:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5638.398746] LNetError: 103295:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5638.416279] LNet: Removed LNI 192.168.203.7@tcp [ 5639.108915] Key type .llcrypt unregistered [ 5639.111759] Key type ._llcrypt unregistered [ 5651.435420] Key type ._llcrypt registered [ 5651.453607] Key type .llcrypt registered [ 5652.980533] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 20:19:47 (1787789987) [ 5667.541459] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5667.556625] alg: No test for adler32 (adler32-zlib) [ 5668.669237] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 5668.872712] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 5670.553229] Key type lgssc registered [ 5671.498600] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5733.730976] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5737.306559] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5753.774828] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5756.179927] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 5767.653070] Lustre: Unmounted lustre-client [ 5873.131165] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5878.189890] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5896.664525] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5935.505907] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 20:24:30 (1787790270) [ 5936.747773] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 5938.302873] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 20:24:33 (1787790273) [ 5967.758619] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5972.326923] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5989.176215] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5995.078902] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 6000.101733] Lustre: lustre-MDT0000-mdc-ffff9f62e6a94800: Connection to lustre-MDT0000 (at 192.168.203.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6005.227141] Lustre: lustre-MDT0001-mdc-ffff9f62e6a94800: Connection to lustre-MDT0001 (at 192.168.203.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6021.605551] Lustre: 104053:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787790341/real 1787790341] req@0000000061c06ff8 x1874633692359680/t0(0) o400->MGC192.168.203.107@tcp@192.168.203.107@tcp:26/25 lens 224/224 e 0 to 1 dl 1787790357 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6021.620778] LustreError: MGC192.168.203.107@tcp: Connection to MGS (at 192.168.203.107@tcp) was lost; in progress operations using this service will fail [ 6141.413929] Lustre: Evicted from MGS (at 192.168.203.107@tcp) after server handle changed from 0xfdb57be141288fd7 to 0xfdb57be14128a674 [ 6141.423576] Lustre: MGC192.168.203.107@tcp: Connection restored to 192.168.203.107@tcp (at 192.168.203.107@tcp) [ 6148.590453] Lustre: 113442:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.203.107@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 6151.684784] LustreError: lustre-MDT0000-mdc-ffff9f62e6a94800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6151.720816] Lustre: lustre-MDT0000-mdc-ffff9f62e6a94800: Connection restored to 192.168.203.107@tcp (at 192.168.203.107@tcp) [ 6156.282603] LustreError: lustre-MDT0001-mdc-ffff9f62e6a94800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6156.315972] Lustre: lustre-MDT0001-mdc-ffff9f62e6a94800: Connection restored to 192.168.203.107@tcp (at 192.168.203.107@tcp) [ 6159.502587] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6160.737934] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6164.954247] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6166.172365] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6178.826047] Lustre: Unmounted lustre-client [ 6275.519874] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6279.523740] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6297.534471] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6332.974643] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 20:31:07 (1787790667) [ 6360.379425] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6365.093953] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6381.663613] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6387.229800] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 6389.324840] LustreError: 120767: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 [ 6394.586817] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 20:32:09 (1787790729) [ 6396.225363] Lustre: Unmounted lustre-client [ 6419.422738] Key type lgssc unregistered [ 6419.649039] LNet: 121775:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6419.655989] LNetError: 121775:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6419.675420] LNet: Removed LNI 192.168.203.7@tcp [ 6420.208845] Key type .llcrypt unregistered [ 6420.213143] Key type ._llcrypt unregistered [ 6429.473748] Key type ._llcrypt registered [ 6429.477158] Key type .llcrypt registered [ 6429.800832] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6429.823141] alg: No test for adler32 (adler32-zlib) [ 6430.899532] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 6431.123226] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 6432.760226] Key type lgssc registered [ 6433.890338] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6502.576398] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6506.645128] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6521.878846] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6620.792702] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6625.112926] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6641.634963] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6684.115459] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 20:36:59 (1787791019) [ 6692.654758] Key type lgssc unregistered [ 6692.844478] LNet: 128980:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6692.851473] LNetError: 128980:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6692.868169] LNet: Removed LNI 192.168.203.7@tcp [ 6693.366601] Key type .llcrypt unregistered [ 6693.374637] Key type ._llcrypt unregistered [ 6704.200851] Key type ._llcrypt registered [ 6704.207814] Key type .llcrypt registered [ 6704.788352] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6704.819499] alg: No test for adler32 (adler32-zlib) [ 6706.000360] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 6706.242816] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 6707.928290] Key type lgssc registered [ 6709.385528] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6748.495793] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6752.452328] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6767.990958] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6861.450831] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6867.353989] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6887.489924] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6923.366427] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 20:40:58 (1787791258) [ 6988.983471] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 6993.503435] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7000.193842] Lustre: DEBUG MARKER: ADD OST3 [ 7011.333565] Lustre: DEBUG MARKER: STOP OST3 [ 7014.880534] Lustre: lustre-OST0000-osc-ffff9f62c3ed6800: disconnect after 24s idle [ 7029.079131] Lustre: DEBUG MARKER: START OST3 again [ 7034.884189] LustreError: lustre-OST0003-osc-ffff9f62c3ed6800: operation ost_connect to node 192.168.203.107@tcp failed: rc = -16 [ 7061.577921] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7061.587879] LustreError: 139786:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9f62c3ed6800: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 7071.933057] Lustre: Unmounted lustre-client [ 7165.524404] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7169.599957] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7187.529357] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7217.733851] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 20:45:52 (1787791552) [ 7284.515957] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7288.211390] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7303.723704] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7352.638397] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7357.039850] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7373.618879] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7375.018975] LNetError: 129407:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 7385.633707] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 7390.547698] Lustre: Unmounted lustre-client [ 7421.007323] Key type lgssc unregistered [ 7430.240263] LNet: 1 local NIs in recovery (showing 1): 192.168.203.7@tcp [ 7435.553357] LNet: 148761:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7435.585107] LNetError: 148761:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7505.256862] LNet: Removed LNI 192.168.203.7@tcp [ 7505.916145] Key type .llcrypt unregistered [ 7505.921354] Key type ._llcrypt unregistered [ 7517.647945] Key type ._llcrypt registered [ 7517.650393] Key type .llcrypt registered [ 7518.657654] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 20:50:53 (1787791853) [ 7530.934195] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7530.955631] alg: No test for adler32 (adler32-zlib) [ 7532.007172] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 7532.205584] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 7533.859223] Key type lgssc registered [ 7534.768540] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7596.039738] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7600.549205] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7618.000776] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7709.657854] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 7714.120412] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7719.355693] Lustre: Unmounted lustre-client [ 7719.477748] LNetError: 149940:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5450:f6ff:fe43:eaaa/1023: rc = -22 [ 7719.492471] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 7719.722510] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 7719.867625] Lustre: Unmounted lustre-client [ 7720.436461] LNetError: 149941:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5450:f6ff:fe43:eaaa/1023: rc = -22 [ 7720.451141] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 7721.116608] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 7721.124622] Lustre: Skipped 2 previous similar messages [ 7721.303164] Lustre: Unmounted lustre-client [ 7721.308826] Lustre: Skipped 2 previous similar messages [ 7722.277251] LNetError: 149941:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5450:f6ff:fe43:eaaa/1023: rc = -22 [ 7722.287235] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 7723.194497] Lustre: 155857: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 [ 7723.438126] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 7723.441151] Lustre: Skipped 4 previous similar messages [ 7723.596613] Lustre: Unmounted lustre-client [ 7723.601621] Lustre: Skipped 4 previous similar messages [ 7723.725336] Lustre: 155886: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 [ 7729.626410] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 20:54:24 (1787792064) [ 7781.026025] LNet: 1 local NIs in recovery (showing 1): 192.168.203.7@tcp [ 7821.230368] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7825.306577] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7845.992995] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7922.003914] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7927.201992] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7941.374881] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7947.553366] LustreError: 161476:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 7992.996342] LNet: 1 local NIs in recovery (showing 1): 192.168.203.7@tcp [ 8055.456257] LNet: 1 local NIs in recovery (showing 1): 192.168.203.7@tcp [ 8085.472693] 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 [ 8085.485614] Lustre: Unmounted lustre-client [ 8085.489523] Lustre: Skipped 1 previous similar message [ 8085.528510] LustreError: 161476:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 8109.156932] Key type lgssc unregistered [ 8131.233956] LNet: 1 local NIs in recovery (showing 1): 192.168.203.7@tcp [ 8138.089058] LNet: 161933:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8138.109520] LNetError: 161933:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8205.737981] LNet: Removed LNI 192.168.203.7@tcp [ 8206.371102] Key type .llcrypt unregistered [ 8206.373141] Key type ._llcrypt unregistered [ 8222.442453] Key type ._llcrypt registered [ 8222.444656] Key type .llcrypt registered [ 8223.755301] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 21:02:38 (1787792558) [ 8238.225364] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8238.243946] alg: No test for adler32 (adler32-zlib) [ 8239.333511] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 8239.596503] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 8241.248341] Key type lgssc registered [ 8242.191468] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8365.443880] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 8370.030640] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8380.113997] Lustre: Unmounted lustre-client [ 8514.654705] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 8520.042537] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8526.613462] Lustre: Unmounted lustre-client [ 8567.680981] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 21:08:22 (1787792902) [ 8651.679882] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8657.555487] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8678.495673] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8794.717970] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 8800.682268] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8813.870619] Lustre: Unmounted lustre-client [ 8918.410602] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 8922.819935] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8939.374305] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 21:14:34 (1787793274) [ 8941.369543] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8951.709660] Lustre: Unmounted lustre-client [ 9123.725097] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 9128.882105] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9136.355856] Lustre: Unmounted lustre-client [ 9136.871374] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 9144.788550] Lustre: Unmounted lustre-client [ 9146.308520] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 21:18:01 (1787793481) [ 9218.518366] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9223.607669] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9244.383884] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9244.984690] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 9335.274664] Lustre: lustre-MDT0000-mdc-ffff9f62d8445800: Connection to lustre-MDT0000 (at 192.168.203.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9340.397183] LustreError: lustre-OST0001-osc-ffff9f62d8445800: operation ost_connect to node 192.168.203.107@tcp failed: rc = -19 [ 9350.635235] LustreError: MGC192.168.203.107@tcp: Connection to MGS (at 192.168.203.107@tcp) was lost; in progress operations using this service will fail [ 9350.644938] LustreError: lustre-MDT0000-mdc-ffff9f62d8445800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9350.665021] Lustre: lustre-MDT0000-mdc-ffff9f62d8445800: Connection restored to 192.168.203.107@tcp (at 192.168.203.107@tcp) [ 9350.668683] Lustre: Evicted from MGS (at 192.168.203.107@tcp) after server handle changed from 0x8b527aadcdbabcd9 to 0x8b527aadcdbac036 [ 9390.836448] Lustre: Unmounted lustre-client [ 9415.886427] Key type lgssc unregistered [ 9416.135621] LNet: 189457:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9416.142318] LNetError: 189457:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9416.160142] LNet: Removed LNI 192.168.203.7@tcp [ 9416.804213] Key type .llcrypt unregistered [ 9416.810494] Key type ._llcrypt unregistered [ 9427.546500] Key type ._llcrypt registered [ 9427.549940] Key type .llcrypt registered [ 9428.734933] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 21:22:43 (1787793763) [ 9431.987757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9432.012338] alg: No test for adler32 (adler32-zlib) [ 9433.298573] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 9433.826175] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 9435.553085] Key type lgssc registered [ 9437.168142] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9488.779312] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9494.317561] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9515.215052] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9516.990235] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [ 9614.321110] LustreError: lustre-OST0001-osc-ffff9f62c3313000: operation ost_connect to node 192.168.203.107@tcp failed: rc = -19 [ 9710.875329] Lustre: Unmounted lustre-client [ 9745.449189] Key type lgssc unregistered [ 9745.694355] LNet: 195479:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9745.701957] LNetError: 195479:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9745.720818] LNet: Removed LNI 192.168.203.7@tcp [ 9746.573501] Key type .llcrypt unregistered [ 9746.578849] Key type ._llcrypt unregistered [ 9758.554482] Key type ._llcrypt registered [ 9758.559157] Key type .llcrypt registered [ 9760.000184] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 21:28:14 (1787794094) [ 9789.154123] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9789.169586] alg: No test for adler32 (adler32-zlib) [ 9790.289236] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [ 9790.589325] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [ 9792.336422] Key type lgssc registered [ 9793.533506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9875.234204] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9882.365293] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9908.532734] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9947.693502] Key type lgssc unregistered [ 9948.115561] LNet: 200461:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9948.126734] LNetError: 200461:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9948.148877] LNet: Removed LNI 192.168.203.7@tcp [ 9949.045268] Key type .llcrypt unregistered [ 9949.047922] Key type ._llcrypt unregistered [10061.603481] Key type ._llcrypt registered [10061.606974] Key type .llcrypt registered [10062.096190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10062.133517] alg: No test for adler32 (adler32-zlib) [10063.337209] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [10063.662330] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [10065.432188] Key type lgssc registered [10066.997925] Lustre: Echo OBD driver; http://www.lustre.org/ [10113.422772] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 21:34:08 (1787794448) [10153.415439] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10159.155664] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10180.735860] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10183.559001] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [10185.809686] Lustre: Unmounted lustre-client [10186.129534] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [10186.330459] Lustre: Unmounted lustre-client [10193.587778] Lustre: Unmounted lustre-client [10221.297135] Key type lgssc unregistered [10221.501872] LNet: 205437:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10221.511548] LNetError: 205437:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10221.536904] LNet: Removed LNI 192.168.203.7@tcp [10222.236960] Key type .llcrypt unregistered [10222.239226] Key type ._llcrypt unregistered [10233.379935] Key type ._llcrypt registered [10233.386237] Key type .llcrypt registered [10234.770456] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 21:36:09 (1787794569) [10237.832132] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10237.845285] alg: No test for adler32 (adler32-zlib) [10239.043426] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [10239.299168] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [10241.064447] Key type lgssc registered [10242.275105] Lustre: Echo OBD driver; http://www.lustre.org/ [10296.045838] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10301.561647] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10322.355965] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10325.021887] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [10332.074121] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10333.471464] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10340.327242] Lustre: lustre-MDT0000-mdc-ffff9f62d16c3800: Connection to lustre-MDT0000 (at 192.168.203.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [10350.573185] Lustre: lustre-MDT0001-mdc-ffff9f62d16c3800: Connection to lustre-MDT0001 (at 192.168.203.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [10361.632178] Lustre: 206112:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787794681/real 1787794681] req@000000008af007d5 x1874638484280576/t0(0) o400->MGC192.168.203.107@tcp@192.168.203.107@tcp:26/25 lens 224/224 e 0 to 1 dl 1787794697 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10361.674825] LustreError: MGC192.168.203.107@tcp: Connection to MGS (at 192.168.203.107@tcp) was lost; in progress operations using this service will fail [10405.866868] Lustre: Evicted from MGS (at 192.168.203.107@tcp) after server handle changed from 0x479ac7b20a25e394 to 0x479ac7b20a25e611 [10405.890105] Lustre: MGC192.168.203.107@tcp: Connection restored to 192.168.203.107@tcp (at 192.168.203.107@tcp) [10421.237700] LustreError: lustre-MDT0000-mdc-ffff9f62d16c3800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10421.253977] Lustre: lustre-MDT0000-mdc-ffff9f62d16c3800: Connection restored to 192.168.203.107@tcp (at 192.168.203.107@tcp) [10433.573162] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10435.426851] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [10440.487092] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10442.172600] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10445.581164] Lustre: Unmounted lustre-client [10472.708633] Key type lgssc unregistered [10472.961826] LNet: 210978:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10472.972369] LNetError: 210978:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10472.994394] LNet: Removed LNI 192.168.203.7@tcp [10473.741739] Key type .llcrypt unregistered [10473.747319] Key type ._llcrypt unregistered [10500.360205] Key type ._llcrypt registered [10500.365904] Key type .llcrypt registered [10500.801300] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10500.824853] alg: No test for adler32 (adler32-zlib) [10501.947587] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [10502.266948] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [10503.944176] Key type lgssc registered [10505.059868] Lustre: Echo OBD driver; http://www.lustre.org/ [10590.419848] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10595.785856] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10618.667325] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10669.611919] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 21:43:23 (1787795003) [10762.678775] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10768.236873] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10790.555576] Lustre: DEBUG MARKER: oleg307-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10792.276329] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [10797.464889] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [10798.803490] Lustre: Mounted lustre-client - version 2.17.57_54_g0142cbb [10798.811537] Lustre: Skipped 1 previous similar message [10803.480179] Lustre: Unmounted lustre-client [10805.074275] Lustre: Unmounted lustre-client [10806.787177] Lustre: Unmounted lustre-client [10809.434120] Lustre: Unmounted lustre-client [10809.440238] Lustre: Skipped 1 previous similar message [10820.195452] Lustre: Unmounted lustre-client [10848.327793] Key type lgssc unregistered [10848.516094] LNet: 220143:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10848.525735] LNetError: 220143:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10848.547597] LNet: Removed LNI 192.168.203.7@tcp [10849.237122] Key type .llcrypt unregistered [10849.239907] Key type ._llcrypt unregistered [10861.321316] Key type ._llcrypt registered [10861.328220] Key type .llcrypt registered [10863.084979] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 21:46:37 (1787795197) [10875.323380] Key type .llcrypt unregistered [10875.325711] Key type ._llcrypt unregistered [10876.481361] Key type ._llcrypt registered [10876.482611] Key type .llcrypt registered [10876.993417] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [10877.012232] alg: No test for adler32 (adler32-zlib) [10878.139815] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [10878.532412] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [10880.312165] Key type lgssc registered [10881.619351] Lustre: Echo OBD driver; http://www.lustre.org/ [10907.701500] Key type lgssc unregistered [10907.926838] LNet: 222307:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10907.942628] LNetError: 222307:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10907.962732] LNet: Removed LNI 192.168.203.7@tcp [10908.585067] Key type .llcrypt unregistered [10908.590914] Key type ._llcrypt unregistered [10908.987933] Key type ._llcrypt registered [10908.990324] Key type .llcrypt registered [10910.410397] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 21:47:24 (1787795244) [10923.561316] Key type .llcrypt unregistered [10923.565384] Key type ._llcrypt unregistered [10925.157388] Key type ._llcrypt registered [10925.178132] Key type .llcrypt registered [10925.583855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10925.597745] alg: No test for adler32 (adler32-zlib) [10926.952229] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [10927.433115] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [10929.256155] Key type lgssc registered [10930.927883] Lustre: Echo OBD driver; http://www.lustre.org/ [10948.330805] Key type lgssc unregistered [10948.644169] LNet: 223866:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10948.656417] LNetError: 223866:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10948.688778] LNet: Removed LNI 192.168.203.7@tcp [10949.447445] Key type .llcrypt unregistered [10949.452069] Key type ._llcrypt unregistered [10957.890925] Key type ._llcrypt registered [10957.893712] Key type .llcrypt registered [10958.271208] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10958.288724] alg: No test for adler32 (adler32-zlib) [10959.372661] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [10959.696470] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [10961.440169] Key type lgssc registered [10962.730576] Lustre: Echo OBD driver; http://www.lustre.org/ [10987.107027] Key type lgssc unregistered [10987.444975] LNet: 225632:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10987.455411] LNetError: 225632:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10987.468240] LNet: Removed LNI 192.168.203.7@tcp [10988.096293] Key type .llcrypt unregistered [10988.098993] Key type ._llcrypt unregistered [10988.497849] Key type ._llcrypt registered [10988.499896] Key type .llcrypt registered [10990.055791] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 21:48:44 (1787795324) [11001.839414] Key type .llcrypt unregistered [11001.846235] Key type ._llcrypt unregistered [11002.696023] Key type ._llcrypt registered [11002.734726] Key type .llcrypt registered [11003.021367] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11003.034983] alg: No test for adler32 (adler32-zlib) [11004.192135] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11004.562882] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11006.304479] Key type lgssc registered [11007.690264] Lustre: Echo OBD driver; http://www.lustre.org/ [11026.015425] Key type lgssc unregistered [11026.303851] LNet: 227185:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11026.308856] LNetError: 227185:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11026.329944] LNet: Removed LNI 192.168.203.7@tcp [11027.153886] Key type .llcrypt unregistered [11027.157393] Key type ._llcrypt unregistered [11035.026971] Key type ._llcrypt registered [11035.054505] Key type .llcrypt registered [11035.648240] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11035.688368] alg: No test for adler32 (adler32-zlib) [11036.756226] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11037.099587] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11038.832179] Key type lgssc registered [11040.177372] Lustre: Echo OBD driver; http://www.lustre.org/ [11057.435881] Key type lgssc unregistered [11057.748556] LNet: 228495:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11057.757406] LNetError: 228495:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11057.788878] LNet: Removed LNI 192.168.203.7@tcp [11058.794751] Key type .llcrypt unregistered [11058.808381] Key type ._llcrypt unregistered [11066.400924] Key type ._llcrypt registered [11066.409281] Key type .llcrypt registered [11066.864654] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11066.895111] alg: No test for adler32 (adler32-zlib) [11068.124320] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11068.459847] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11070.216378] Key type lgssc registered [11071.300289] Lustre: Echo OBD driver; http://www.lustre.org/ [11087.142307] Key type lgssc unregistered [11087.312207] LNet: 229804:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11087.320509] LNetError: 229804:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11087.334635] LNet: Removed LNI 192.168.203.7@tcp [11087.940258] Key type .llcrypt unregistered [11087.944338] Key type ._llcrypt unregistered [11096.424119] Key type ._llcrypt registered [11096.426053] Key type .llcrypt registered [11097.118572] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11097.138974] alg: No test for adler32 (adler32-zlib) [11098.632363] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11099.223673] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11101.152208] Key type lgssc registered [11102.511570] Lustre: Echo OBD driver; http://www.lustre.org/ [11129.057236] Key type lgssc unregistered [11129.392571] LNet: 231575:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11129.403394] LNetError: 231575:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11129.427823] LNet: Removed LNI 192.168.203.7@tcp [11130.181769] Key type .llcrypt unregistered [11130.183725] Key type ._llcrypt unregistered [11130.618211] Key type ._llcrypt registered [11130.621290] Key type .llcrypt registered [11131.988877] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 21:51:06 (1787795466) [11143.829290] Key type .llcrypt unregistered [11143.836620] Key type ._llcrypt unregistered [11145.178620] Key type ._llcrypt registered [11145.191791] Key type .llcrypt registered [11145.532418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11145.561434] alg: No test for adler32 (adler32-zlib) [11146.639261] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11146.850957] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11148.556074] Key type lgssc registered [11149.710266] Lustre: Echo OBD driver; http://www.lustre.org/ [11165.457614] Key type lgssc unregistered [11165.761355] LNet: 233134:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11165.768464] LNetError: 233134:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11165.790490] LNet: Removed LNI 192.168.203.7@tcp [11166.682745] Key type .llcrypt unregistered [11166.689052] Key type ._llcrypt unregistered [11174.305514] Key type ._llcrypt registered [11174.310227] Key type .llcrypt registered [11174.844583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11174.860929] alg: No test for adler32 (adler32-zlib) [11175.927645] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11176.276939] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11178.040179] Key type lgssc registered [11179.325487] Lustre: Echo OBD driver; http://www.lustre.org/ [11197.525782] Key type lgssc unregistered [11197.853100] LNet: 234453:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11197.871943] LNetError: 234453:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11197.901707] LNet: Removed LNI 192.168.203.7@tcp [11198.635335] Key type .llcrypt unregistered [11198.640172] Key type ._llcrypt unregistered [11206.672499] Key type ._llcrypt registered [11206.684421] Key type .llcrypt registered [11207.101182] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11207.118323] alg: No test for adler32 (adler32-zlib) [11208.332211] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11208.599409] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11210.304181] Key type lgssc registered [11211.855151] Lustre: Echo OBD driver; http://www.lustre.org/ [11229.364193] Key type lgssc unregistered [11229.610849] LNet: 235763:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11229.616777] LNetError: 235763:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11229.637095] LNet: Removed LNI 192.168.203.7@tcp [11230.380581] Key type .llcrypt unregistered [11230.384553] Key type ._llcrypt unregistered [11239.123985] Key type ._llcrypt registered [11239.129788] Key type .llcrypt registered [11239.790236] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11239.808845] alg: No test for adler32 (adler32-zlib) [11241.069932] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11241.403036] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11243.168351] Key type lgssc registered [11244.744408] Lustre: Echo OBD driver; http://www.lustre.org/ [11263.762199] Key type lgssc unregistered [11264.094676] LNet: 237076:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11264.105364] LNetError: 237076:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11264.126548] LNet: Removed LNI 192.168.203.7@tcp [11264.848374] Key type .llcrypt unregistered [11264.855636] Key type ._llcrypt unregistered [11273.460456] Key type ._llcrypt registered [11273.466974] Key type .llcrypt registered [11274.470447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11274.512533] alg: No test for adler32 (adler32-zlib) [11275.969119] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11276.520036] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11278.432180] Key type lgssc registered [11280.018193] Lustre: Echo OBD driver; http://www.lustre.org/ [11298.819077] Key type lgssc unregistered [11299.093688] LNet: 238393:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11299.102077] LNetError: 238393:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11299.120101] LNet: Removed LNI 192.168.203.7@tcp [11299.828439] Key type .llcrypt unregistered [11299.830539] Key type ._llcrypt unregistered [11308.238429] Key type ._llcrypt registered [11308.241390] Key type .llcrypt registered [11308.679455] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11308.694017] alg: No test for adler32 (adler32-zlib) [11310.007819] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11310.318784] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11312.016468] Key type lgssc registered [11313.417931] Lustre: Echo OBD driver; http://www.lustre.org/ [11336.698307] Key type lgssc unregistered [11337.070480] LNet: 239702:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11337.084542] LNetError: 239702:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11337.110220] LNet: Removed LNI 192.168.203.7@tcp [11338.021632] Key type .llcrypt unregistered [11338.031159] Key type ._llcrypt unregistered [11366.803872] Key type ._llcrypt registered [11366.807251] Key type .llcrypt registered [11369.089243] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 21:55:02 (1787795702) [11381.627996] Key type .llcrypt unregistered [11381.633054] Key type ._llcrypt unregistered [11383.476537] Key type ._llcrypt registered [11383.490645] Key type .llcrypt registered [11384.388241] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11384.405153] alg: No test for adler32 (adler32-zlib) [11385.904040] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11386.324154] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11388.168153] Key type lgssc registered [11390.174527] Lustre: Echo OBD driver; http://www.lustre.org/ [11409.700407] Key type lgssc unregistered [11409.958215] LNet: 242033:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11409.965721] LNetError: 242033:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11409.986062] LNet: Removed LNI 192.168.203.7@tcp [11410.786545] Key type .llcrypt unregistered [11410.790684] Key type ._llcrypt unregistered [11419.534199] Key type ._llcrypt registered [11419.538328] Key type .llcrypt registered [11420.009333] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11420.031976] alg: No test for adler32 (adler32-zlib) [11421.223513] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11421.542753] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11423.224231] Key type lgssc registered [11424.737719] Lustre: Echo OBD driver; http://www.lustre.org/ [11443.405401] Key type lgssc unregistered [11443.748516] LNet: 243357:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11443.762609] LNetError: 243357:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11443.794456] LNet: Removed LNI 192.168.203.7@tcp [11444.417823] Key type .llcrypt unregistered [11444.423641] Key type ._llcrypt unregistered [11452.495766] Key type ._llcrypt registered [11452.497054] Key type .llcrypt registered [11452.813593] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11452.827328] alg: No test for adler32 (adler32-zlib) [11454.078552] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11454.373813] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11456.112190] Key type lgssc registered [11457.585544] Lustre: Echo OBD driver; http://www.lustre.org/ [11474.946363] Key type lgssc unregistered [11475.278780] LNet: 244668:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11475.283520] LNetError: 244668:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11475.295496] LNet: Removed LNI 192.168.203.7@tcp [11475.908954] Key type .llcrypt unregistered [11475.911862] Key type ._llcrypt unregistered [11485.749163] Key type ._llcrypt registered [11485.765050] Key type .llcrypt registered [11486.191787] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11486.209228] alg: No test for adler32 (adler32-zlib) [11487.477537] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11487.773174] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11489.530648] Key type lgssc registered [11491.346738] Lustre: Echo OBD driver; http://www.lustre.org/ [11516.610615] Key type lgssc unregistered [11516.965992] LNet: 246463:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11516.982314] LNetError: 246463:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11517.015549] LNet: Removed LNI 192.168.203.7@tcp [11517.899683] Key type .llcrypt unregistered [11517.901107] Key type ._llcrypt unregistered [11518.541786] Key type ._llcrypt registered [11518.545409] Key type .llcrypt registered [11520.099948] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 21:57:34 (1787795854) [11541.523353] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11541.537819] alg: No test for adler32 (adler32-zlib) [11542.812300] Lustre: Lustre: Build Version: 2.17.57_54_g0142cbb [11543.150839] LNet: Added LNI 192.168.203.7@tcp [8/256/0/180] [11544.952413] Key type lgssc registered [11546.930944] Lustre: Echo OBD driver; http://www.lustre.org/ [11773.137968] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 22:01:48 (1787796108) [11774.755556] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [11828.556916] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 11583 sec ========= 22:02:43 (1787796163) [11829.978267] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 22:02:44 (1787796164) === [11833.241702] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 22:02:48 (1787796168) === [11864.492931] Key type lgssc unregistered [11864.718024] LNet: 255543:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11864.727924] LNetError: 255543:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11864.742431] LNet: Removed LNI 192.168.203.7@tcp [11865.464139] Key type .llcrypt unregistered [11865.468637] Key type ._llcrypt unregistered