[ 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 444160220 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.001013] APIC: Switch to symmetric I/O mode setup [ 0.002394] x2apic enabled [ 0.003010] Switched APIC routing to physical x2apic. [ 0.004015] kvm-guest: setup PV IPIs [ 0.007354] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008022] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009016] pid_max: default: 32768 minimum: 301 [ 0.010145] LSM: Security Framework initializing [ 0.011055] Yama: becoming mindful. [ 0.012040] SELinux: Initializing. [ 0.013074] *** VALIDATE selinux *** [ 0.021895] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026646] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027210] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029074] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030128] *** VALIDATE tmpfs *** [ 0.032099] *** VALIDATE proc *** [ 0.033194] *** VALIDATE cgroup *** [ 0.034010] *** VALIDATE cgroup2 *** [ 0.036232] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038136] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040040] Spectre V2 : User space: Vulnerable [ 0.041009] Speculative Store Bypass: Vulnerable [ 0.044473] debug: unmapping init [mem 0xffffffff86459000-0xffffffff86460fff] [ 0.046172] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047749] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048022] ... version: 2 [ 0.049013] ... bit width: 48 [ 0.050013] ... generic registers: 4 [ 0.051016] ... value mask: 0000ffffffffffff [ 0.052016] ... max period: 00007fffffffffff [ 0.053016] ... fixed-purpose events: 3 [ 0.054011] ... event mask: 000000070000000f [ 0.055335] rcu: Hierarchical SRCU implementation. [ 0.057469] smp: Bringing up secondary CPUs ... [ 0.058578] x86: Booting SMP configuration: [ 0.059035] .... node #0, CPUs: #1 #2 #3 [ 0.063035] smp: Brought up 1 node, 4 CPUs [ 0.065017] smpboot: Max logical packages: 1 [ 0.066020] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.138891] node 0 deferred pages initialised in 69ms [ 0.142403] devtmpfs: initialized [ 0.144185] x86/mm: Memory block size: 128MB [ 0.146915] gcov: version magic: 0x41383552 [ 0.149292] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.153099] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.155294] pinctrl core: initialized pinctrl subsystem [ 0.157166] [ 0.157941] ************************************************************* [ 0.161015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.163011] ** ** [ 0.166014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.168013] ** ** [ 0.170011] ** This means that this kernel is built to expose internal ** [ 0.172013] ** IOMMU data structures, which may compromise security on ** [ 0.175012] ** your system. ** [ 0.177019] ** ** [ 0.179013] ** If you see this message and you are not debugging the ** [ 0.182013] ** kernel, report this immediately to your vendor! ** [ 0.185013] ** ** [ 0.188014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.190047] ************************************************************* [ 0.193569] NET: Registered protocol family 16 [ 0.195444] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.199062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.202064] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.205487] cpuidle: using governor menu [ 0.206747] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.208537] PCI: Using configuration type 1 for base access [ 0.211134] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.221050] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.222018] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.224076] cryptd: max_cpu_qlen set to 1000 [ 0.226274] ACPI: Added _OSI(Module Device) [ 0.227042] ACPI: Added _OSI(Processor Device) [ 0.229016] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.231015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.236706] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.242308] ACPI: Interpreter enabled [ 0.244063] ACPI: PM: (supports S0 S3 S4 S5) [ 0.246038] ACPI: Using IOAPIC for interrupt routing [ 0.249128] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.252598] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.265390] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.268037] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.270024] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.275083] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.279970] acpiphp: Slot [2] registered [ 0.281095] acpiphp: Slot [5] registered [ 0.283090] acpiphp: Slot [6] registered [ 0.285098] acpiphp: Slot [3] registered [ 0.286138] acpiphp: Slot [4] registered [ 0.288104] acpiphp: Slot [7] registered [ 0.290102] acpiphp: Slot [8] registered [ 0.292143] acpiphp: Slot [9] registered [ 0.293105] acpiphp: Slot [10] registered [ 0.294088] acpiphp: Slot [11] registered [ 0.295118] acpiphp: Slot [12] registered [ 0.297124] acpiphp: Slot [13] registered [ 0.298081] acpiphp: Slot [14] registered [ 0.300088] acpiphp: Slot [15] registered [ 0.301162] acpiphp: Slot [16] registered [ 0.303100] acpiphp: Slot [17] registered [ 0.305378] acpiphp: Slot [18] registered [ 0.307104] acpiphp: Slot [19] registered [ 0.308119] acpiphp: Slot [20] registered [ 0.310113] acpiphp: Slot [21] registered [ 0.312128] acpiphp: Slot [22] registered [ 0.313128] acpiphp: Slot [23] registered [ 0.315140] acpiphp: Slot [24] registered [ 0.316081] acpiphp: Slot [25] registered [ 0.318108] acpiphp: Slot [26] registered [ 0.319170] acpiphp: Slot [27] registered [ 0.321104] acpiphp: Slot [28] registered [ 0.323117] acpiphp: Slot [29] registered [ 0.324108] acpiphp: Slot [30] registered [ 0.326137] acpiphp: Slot [31] registered [ 0.328071] PCI host bridge to bus 0000:00 [ 0.330022] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.334027] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.338026] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.342123] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.344029] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.347028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.349184] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.353105] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.356000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.363646] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.368059] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.371022] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.373013] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.375016] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.377624] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.380942] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.386053] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.388801] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.393014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.404013] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.408013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.413721] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.421014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.429016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.443014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.451692] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.459231] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.467015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.482018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.495037] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.498395] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.500391] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.501300] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.505526] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.511061] iommu: Default domain type: Passthrough [ 0.514534] SCSI subsystem initialized [ 0.516169] ACPI: bus type USB registered [ 0.517091] usbcore: registered new interface driver usbfs [ 0.519077] usbcore: registered new interface driver hub [ 0.521092] usbcore: registered new device driver usb [ 0.523179] pps_core: LinuxPPS API ver. 1 registered [ 0.524012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.527061] PTP clock support registered [ 0.529088] EDAC MC: Ver: 3.0.0 [ 0.530336] PCI: Using ACPI for IRQ routing [ 0.531654] NetLabel: Initializing [ 0.533009] NetLabel: domain hash size = 128 [ 0.534006] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.536075] NetLabel: unlabeled traffic allowed by default [ 0.538004] vgaarb: loaded [ 0.539285] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.541012] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.547000] clocksource: Switched to clocksource kvm-clock [ 0.661791] VFS: Disk quotas dquot_6.6.0 [ 0.663535] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.666151] *** VALIDATE ramfs *** [ 0.667330] *** VALIDATE hugetlbfs *** [ 0.670124] pnp: PnP ACPI init [ 0.672349] pnp: PnP ACPI: found 6 devices [ 0.695067] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.698769] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.702176] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.705400] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.708949] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.712385] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.716372] NET: Registered protocol family 2 [ 0.719708] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.725764] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.731105] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.737953] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.741679] TCP: Hash tables configured (established 65536 bind 65536) [ 0.744900] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.748482] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.751220] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.754363] NET: Registered protocol family 1 [ 0.756534] RPC: Registered named UNIX socket transport module. [ 0.758625] RPC: Registered udp transport module. [ 0.760499] RPC: Registered tcp transport module. [ 0.762505] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.765111] NET: Registered protocol family 44 [ 0.766917] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.768894] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.771661] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.774438] PCI: CLS 0 bytes, default 64 [ 0.776090] Unpacking initramfs... [ 2.161728] debug: unmapping init [mem 0xffff8a8a3cc64000-0xffff8a8a3ffcffff] [ 2.166128] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.168654] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.171762] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.657786] Initialise system trusted keyrings [ 2.659740] Key type blacklist registered [ 2.661707] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.673154] zbud: loaded [ 2.676000] *** VALIDATE nfs *** [ 2.677383] *** VALIDATE nfs4 *** [ 2.679061] pstore: using deflate compression [ 2.683929] Platform Keyring initialized [ 2.790497] NET: Registered protocol family 38 [ 2.792316] Key type asymmetric registered [ 2.793801] Asymmetric key parser 'x509' registered [ 2.795238] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.798042] io scheduler mq-deadline registered [ 2.799906] io scheduler kyber registered [ 2.801167] io scheduler bfq registered [ 2.803200] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.806522] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.809314] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.812638] ACPI: Power Button [PWRF] [ 2.818568] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.825337] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.837364] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.864537] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.895449] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.900393] Non-volatile memory driver v1.3 [ 2.902505] Linux agpgart interface v0.103 [ 2.936107] virtio_blk virtio1: [vda] 146152 512-byte logical blocks (74.8 MB/71.4 MiB) [ 2.939422] vda: detected capacity change from 0 to 74829824 [ 2.953492] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.956233] vdb: detected capacity change from 0 to 1073741824 [ 2.964462] libphy: Fixed MDIO Bus: probed [ 2.969634] usbcore: registered new interface driver usbserial_generic [ 2.972343] usbserial: USB Serial support registered for generic [ 2.974357] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.978456] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.980351] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.983265] mousedev: PS/2 mouse device common for all mice [ 2.986588] rtc_cmos 00:05: RTC can wake from S4 [ 2.989847] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.991031] rtc_cmos 00:05: registered as rtc0 [ 2.996352] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.999589] intel_pstate: CPU model not supported [ 3.002560] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.003983] hid: raw HID events driver (C) Jiri Kosina [ 3.008402] usbcore: registered new interface driver usbhid [ 3.010433] usbhid: USB HID core driver [ 3.012341] drop_monitor: Initializing network drop monitor service [ 3.012532] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.014933] Initializing XFRM netlink socket [ 3.020245] NET: Registered protocol family 10 [ 3.023247] Segment Routing with IPv6 [ 3.024918] NET: Registered protocol family 17 [ 3.027485] mpls_gso: MPLS GSO support [ 3.032262] RAS: Correctable Errors collector initialized. [ 3.034713] AVX version of gcm_enc/dec engaged. [ 3.036438] AES CTR mode by8 optimization enabled [ 3.105662] sched_clock: Marking stable (3105637654, 0)->(4010746831, -905109177) [ 3.109490] registered taskstats version 1 [ 3.112697] Loading compiled-in X.509 certificates [ 3.114894] zswap: loaded using pool lzo/zbud [ 3.138851] Key type big_key registered [ 3.151412] Key type encrypted registered [ 3.153455] ima: No TPM chip found, activating TPM-bypass! [ 3.155826] ima: Allocated hash algorithm: sha1 [ 3.157761] ima: No architecture policies found [ 3.159599] evm: Initialising EVM extended attributes: [ 3.161497] evm: security.selinux [ 3.162830] evm: security.ima [ 3.164196] evm: security.capability [ 3.165342] evm: HMAC attrs: 0x1 [ 3.167694] rtc_cmos 00:05: setting system clock to 2026-08-26 01:00:39 UTC (1787706039) [ 3.174106] debug: unmapping init [mem 0xffffffff87403000-0xffffffff875fffff] [ 3.177136] debug: unmapping init [mem 0xffffffff86182000-0xffffffff86458fff] [ 3.186097] Write protecting the kernel read-only data: 28672k [ 3.189122] debug: unmapping init [mem 0xffffffff84803000-0xffffffff849fffff] [ 3.191982] debug: unmapping init [mem 0xffffffff85114000-0xffffffff851fffff] [ 3.219057] 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.228085] systemd[1]: Detected virtualization kvm. [ 3.229272] systemd[1]: Detected architecture x86-64. [ 3.231195] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.253346] systemd[1]: No hostname configured. [ 3.255178] systemd[1]: Set hostname to . [ 3.256908] random: systemd: uninitialized urandom read (16 bytes read) [ 3.259474] systemd[1]: Initializing machine ID from random generator. [ 3.310668] random: ln: uninitialized urandom read (6 bytes read) [ 3.383522] random: systemd: uninitialized urandom read (16 bytes read) [ 3.385341] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.388172] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.391029] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... Starting Journal Service... [ OK ] Reached target Sockets. Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.955901] device-mapper: uevent: version 1.0.3 [ 3.958914] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.598973] virtio_net virtio0 ens2: renamed from eth0 [ 4.667421] random: fast init done [ 4.760988] scsi host0: ata_piix [ 4.807221] scsi host1: ata_piix [ 4.813193] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.816260] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.693877] random: crng init done [ 9.700687] random: 7 urandom warning(s) missed due to ratelimiting [ 10.763861] dracut-initqueue[578]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 13.465583] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ 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 dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Local Encrypted Volumes. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 17.045161] printk: systemd: 26 output lines suppressed due to ratelimiting [ 17.768809] SELinux: Disabled at runtime. [ 17.913496] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 17.953316] systemd[1]: Detected virtualization kvm. [ 17.962455] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 20.067456] systemd[1]: initrd-switch-root.service: Succeeded. [ 20.083744] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 20.098843] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 20.110141] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 20.115794] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 20.128918] systemd[1]: Starting Journal Service... Starting Journal Service... [ 20.143403] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Create list of required st…ce nodes for the current kernel... Mounting Kernel Debug File System... Mounting Huge Pages File System... [ 20.344616] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Created slice User and Session Slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Starting Remount Root and Kernel File Systems... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting POSIX Message Queue File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-getty.slice. Starting Apply Kernel Variables... [ OK ] Reached target rpc_pipefs.target. [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 22.050851] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 22.729116] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 22.825942] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 23.722001] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 23.857901] EDAC sbridge: Ver: 1.1.2 [ 24.836010] hrtimer: interrupt took 4998277 ns [ 27.581858] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit)[ 27.955469] NFS: Registering the id_resolver key type [ 27.957992] Key type id_resolver registered [ 27.960247] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started irqbalance daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started OpenSSH server daemon. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ 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 System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg442-client login: [ 109.599316] libcfs: loading out-of-tree module taints kernel. [ 109.882243] Key type ._llcrypt registered [ 109.887339] Key type .llcrypt registered [ 110.530682] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 110.558200] alg: No test for adler32 (adler32-zlib) [ 112.241043] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 113.504706] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 115.384841] Key type lgssc registered [ 118.130767] Lustre: Echo OBD driver; http://www.lustre.org/ [ 285.416735] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 291.155687] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 308.482857] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing check_logdir /tmp/testlogs/ [ 311.264415] Lustre: lustre-OST0000-osc-ffff8a8a82c71000: disconnect after 23s idle [ 313.427502] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing yml_node [ 318.846978] Lustre: DEBUG MARKER: Client: 2.17.57.80 [ 321.241723] Lustre: DEBUG MARKER: MDS: 2.17.57.80 [ 324.520449] Lustre: DEBUG MARKER: OSS: 2.17.57.80 [ 326.158984] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Aug 25 21:06:01 EDT 2026 [ 345.933740] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 347.784928] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 351.438712] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 361.738191] Lustre: Unmounted lustre-client [ 477.862896] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 484.027161] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 507.726717] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 551.126464] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 21:09:45 (1787706585) [ 598.760094] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 604.545262] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 642.479369] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 693.352565] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 699.399974] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 749.029122] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 778.480945] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 21:13:32 (1787706812) [ 906.082779] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 913.160599] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 939.888453] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 940.583494] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 967.401928] Lustre: Unmounted lustre-client [ 998.179609] Key type lgssc unregistered [ 998.558320] LNet: 21918:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 998.570600] LNetError: 21918:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 998.585809] LNet: Removed LNI 192.168.204.42@tcp [ 999.741264] Key type .llcrypt unregistered [ 999.750322] Key type ._llcrypt unregistered [ 1016.104342] Key type ._llcrypt registered [ 1016.107031] Key type .llcrypt registered [ 1017.602201] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 21:17:32 (1787707052) [ 1020.503403] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1020.526493] alg: No test for adler32 (adler32-zlib) [ 1021.595571] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 1021.946989] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 1023.696638] Key type lgssc registered [ 1025.113883] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1079.677782] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1086.468985] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1108.398305] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1111.225824] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 1127.938210] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 21:19:22 (1787707162) [ 1139.645574] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 21:19:34 (1787707174) [ 1149.654461] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 21:19:44 (1787707184) [ 1162.714705] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 21:19:57 (1787707197) [ 1191.924792] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 21:20:25 (1787707225) [ 1213.698827] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 21:20:47 (1787707247) [ 1229.605493] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 21:21:04 (1787707264) [ 1241.097461] Lustre: Unmounted lustre-client [ 1242.011195] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 1246.426626] Lustre: Unmounted lustre-client [ 1284.212286] Key type lgssc unregistered [ 1284.479480] LNet: 30297:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1284.493073] LNetError: 30297:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1284.527526] LNet: Removed LNI 192.168.204.42@tcp [ 1285.455118] Key type .llcrypt unregistered [ 1285.465719] Key type ._llcrypt unregistered [ 1322.791490] Key type ._llcrypt registered [ 1322.795610] Key type .llcrypt registered [ 1323.175814] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1323.185556] alg: No test for adler32 (adler32-zlib) [ 1324.258949] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 1324.506444] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 1326.208232] Key type lgssc registered [ 1327.805576] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1415.392321] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1425.005284] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1457.115032] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1515.096538] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 21:25:49 (1787707549) [ 1551.854174] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1558.961993] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1582.867397] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1584.675596] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 1737.552340] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 21:29:32 (1787707772) [ 1792.349582] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 21:30:26 (1787707826) [ 1804.422880] Lustre: Unmounted lustre-client [ 1902.199724] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1910.420529] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1938.822977] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1939.662141] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 1946.141849] Lustre: Unmounted lustre-client [ 1979.652308] Key type lgssc unregistered [ 1980.124615] LNet: 41642:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1980.144142] LNetError: 41642:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1980.172309] LNet: Removed LNI 192.168.204.42@tcp [ 1981.400058] Key type .llcrypt unregistered [ 1981.402428] Key type ._llcrypt unregistered [ 2002.813639] Key type ._llcrypt registered [ 2002.815994] Key type .llcrypt registered [ 2004.351179] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 21:33:58 (1787708038) [ 2007.558474] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2007.586467] alg: No test for adler32 (adler32-zlib) [ 2008.767104] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 2009.075779] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 2010.792333] Key type lgssc registered [ 2012.306791] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2073.083930] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2079.953688] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2104.806071] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2112.089402] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2128.803767] Lustre: Unmounted lustre-client [ 2219.157752] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2226.368533] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2251.091368] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2252.095127] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2259.516444] Lustre: Unmounted lustre-client [ 2287.981232] Key type lgssc unregistered [ 2288.334833] LNet: 47187:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2288.344734] LNetError: 47187:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2288.368797] LNet: Removed LNI 192.168.204.42@tcp [ 2289.007765] Key type .llcrypt unregistered [ 2289.011271] Key type ._llcrypt unregistered [ 2303.369150] Key type ._llcrypt registered [ 2303.376781] Key type .llcrypt registered [ 2304.777697] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 21:38:59 (1787708339) [ 2308.067100] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2308.101513] alg: No test for adler32 (adler32-zlib) [ 2309.258546] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 2309.523060] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 2311.240253] Key type lgssc registered [ 2312.641470] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2368.191995] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2374.118561] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2395.570487] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2398.671230] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2513.978741] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 21:42:28 (1787708548) [ 2515.545916] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2517.435527] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 21:42:32 (1787708552) [ 2519.640483] Lustre: Unmounted lustre-client [ 2549.022760] Key type lgssc unregistered [ 2549.309119] LNet: 52532:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2549.316383] LNetError: 52532:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2549.343150] LNet: Removed LNI 192.168.204.42@tcp [ 2550.054911] Key type .llcrypt unregistered [ 2550.058316] Key type ._llcrypt unregistered [ 2578.192887] Key type ._llcrypt registered [ 2578.205532] Key type .llcrypt registered [ 2578.659889] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2578.671888] alg: No test for adler32 (adler32-zlib) [ 2579.896261] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 2580.164990] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 2581.816456] Key type lgssc registered [ 2583.163662] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2620.400863] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 21:44:14 (1787708654) [ 2634.194402] Key type lgssc unregistered [ 2634.453303] LNet: 54950:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2634.465956] LNetError: 54950:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2634.491781] LNet: Removed LNI 192.168.204.42@tcp [ 2635.266051] Key type .llcrypt unregistered [ 2635.270680] Key type ._llcrypt unregistered [ 2648.905376] Key type ._llcrypt registered [ 2648.912876] Key type .llcrypt registered [ 2649.138881] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2649.170932] alg: No test for adler32 (adler32-zlib) [ 2650.366564] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 2650.649717] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 2652.304646] Key type lgssc registered [ 2653.697296] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2712.315419] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2718.980307] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2742.712316] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2754.099789] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2807.241179] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 21:47:22 (1787708842) [ 2809.557180] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2812.197659] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 21:47:26 (1787708846) [ 2814.731626] Lustre: Unmounted lustre-client [ 2888.430254] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2894.969949] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2968.382834] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 21:50:03 (1787709003) [ 3072.887960] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 3078.506418] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3093.486763] Lustre: lustre-MDT0001-mdc-ffff8a8a996a1800: Connection to lustre-MDT0001 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3098.592624] Lustre: lustre-OST0000-osc-ffff8a8a996a1800: disconnect after 23s idle [ 3110.388234] LustreError: 63671:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.204.142@tcp: cfg command failed: rc = -17 [ 3110.405625] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 3110.405625] [ 3110.414351] LustreError: 62914:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 3123.835592] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 21:52:38 (1787709158) [ 3185.518924] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3277.155333] Lustre: Unmounted lustre-client [ 3450.680038] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 3456.361123] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3515.478433] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 21:59:10 (1787709550) [ 3517.725888] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3518.369756] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3518.380440] Lustre: Skipped 2 previous similar messages [ 3539.806473] Lustre: Unmounted lustre-client [ 3696.245937] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 22:02:11 (1787709731) [ 3698.351153] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3700.531497] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 22:02:15 (1787709735) [ 3800.901250] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 3807.100447] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4557.108840] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 22:16:32 (1787710592) [ 4559.839615] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4570.233195] Lustre: Unmounted lustre-client [ 4717.233656] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4723.172687] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4750.169582] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4751.052425] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 6908.929468] Lustre: 95427:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff8a8a82e20000: catlog [0xa:0x5:0x0] crosses index zero [ 6942.246237] Lustre: Unmounted lustre-client [ 6971.500731] Key type lgssc unregistered [ 6971.763845] LNet: 103657:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6971.774136] LNetError: 103657:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6971.799058] LNet: Removed LNI 192.168.204.42@tcp [ 6972.527656] Key type .llcrypt unregistered [ 6972.530512] Key type ._llcrypt unregistered [ 6984.630310] Key type ._llcrypt registered [ 6984.634938] Key type .llcrypt registered [ 6986.128814] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 22:57:00 (1787713020) [ 7006.332833] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7006.345317] alg: No test for adler32 (adler32-zlib) [ 7007.609977] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 7008.032755] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7009.752304] Key type lgssc registered [ 7011.582641] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7107.794246] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7113.079760] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7136.357731] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7138.125057] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 7154.269868] Lustre: Unmounted lustre-client [ 7288.249556] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7294.805909] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7319.179194] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7358.720426] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 23:03:13 (1787713393) [ 7360.548677] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 7362.919205] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 23:03:17 (1787713397) [ 7404.130913] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7410.784547] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7435.690381] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7436.629934] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 7447.031612] Lustre: lustre-MDT0000-mdc-ffff8a8a996a7800: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7457.266935] Lustre: lustre-MDT0001-mdc-ffff8a8a996a7800: Connection to lustre-MDT0001 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7468.512525] Lustre: 104417:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787713488/real 1787713488] req@000000002a1fea59 x1874552992902400/t0(0) o400->MGC192.168.204.142@tcp@192.168.204.142@tcp:26/25 lens 224/224 e 0 to 1 dl 1787713504 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7468.544134] LustreError: MGC192.168.204.142@tcp: Connection to MGS (at 192.168.204.142@tcp) was lost; in progress operations using this service will fail [ 7604.737626] LustreError: lustre-MDT0000-mdc-ffff8a8a996a7800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 7604.772865] Lustre: lustre-MDT0000-mdc-ffff8a8a996a7800: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [ 7609.842945] LustreError: lustre-MDT0001-mdc-ffff8a8a996a7800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7609.861325] Lustre: lustre-MDT0001-mdc-ffff8a8a996a7800: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [ 7613.931184] Lustre: Evicted from MGS (at 192.168.204.142@tcp) after server handle changed from 0xa5246b9ddf1fb35 to 0xa5246b9ddf2137d [ 7613.951378] Lustre: MGC192.168.204.142@tcp: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [ 7614.455396] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7616.241256] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7622.575906] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7624.463825] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7643.572513] Lustre: Unmounted lustre-client [ 7780.858614] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7788.840800] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7815.112077] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7859.561954] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 23:11:34 (1787713894) [ 7895.866434] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7903.170795] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7927.137914] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7927.839515] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 7933.320599] LustreError: 121144: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 [ 7940.845730] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 23:12:55 (1787713975) [ 7943.238427] Lustre: Unmounted lustre-client [ 7971.937484] Key type lgssc unregistered [ 7972.224184] LNet: 122154:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7972.231774] LNetError: 122154:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7972.263748] LNet: Removed LNI 192.168.204.42@tcp [ 7973.313531] Key type .llcrypt unregistered [ 7973.317825] Key type ._llcrypt unregistered [ 7988.103939] Key type ._llcrypt registered [ 7988.110051] Key type .llcrypt registered [ 7989.259460] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7989.284738] alg: No test for adler32 (adler32-zlib) [ 7990.378962] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 7990.642335] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7992.320529] Key type lgssc registered [ 7993.922382] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8085.591662] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8092.292423] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8116.979937] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8251.429308] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8257.950297] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8286.692348] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8335.215885] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 23:19:29 (1787714369) [ 8352.022312] Key type lgssc unregistered [ 8352.365748] LNet: 129377:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8352.373658] LNetError: 129377:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8352.420812] LNet: Removed LNI 192.168.204.42@tcp [ 8353.097994] Key type .llcrypt unregistered [ 8353.100074] Key type ._llcrypt unregistered [ 8368.112367] Key type ._llcrypt registered [ 8368.131115] Key type .llcrypt registered [ 8368.942213] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8368.956409] alg: No test for adler32 (adler32-zlib) [ 8370.234798] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 8370.733276] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 8372.570395] Key type lgssc registered [ 8374.779780] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8435.497521] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8443.268400] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8470.442815] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8606.759211] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8612.964923] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8642.514656] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8689.037856] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 23:25:23 (1787714723) [ 8780.913260] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 8787.125971] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8799.828407] Lustre: DEBUG MARKER: ADD OST3 [ 8806.371092] Lustre: lustre-OST0000-osc-ffff8a8a834aa800: disconnect after 23s idle [ 8813.853354] Lustre: DEBUG MARKER: STOP OST3 [ 8829.928447] Lustre: lustre-OST0003-osc-ffff8a8a834aa800: Connection to lustre-OST0003 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8838.237296] Lustre: DEBUG MARKER: START OST3 again [ 8847.986671] Lustre: lustre-OST0003-osc-ffff8a8a834aa800: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [ 8879.274793] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8879.288271] LustreError: 140187:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8a8a834aa800: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 8889.688285] Lustre: Unmounted lustre-client [ 9042.829986] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9050.663575] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9076.734550] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9133.176723] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 23:32:47 (1787715167) [ 9244.059436] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9250.561571] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9277.036775] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9355.760361] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9363.137179] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9387.003242] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9389.695788] LNetError: 129789:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 9400.373281] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 9405.646819] Lustre: Unmounted lustre-client [ 9434.587639] Key type lgssc unregistered [ 9439.840422] LNet: 1 local NIs in recovery (showing 1): 192.168.204.42@tcp [ 9446.245929] LNet: 149185:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9446.260297] LNetError: 149185:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9521.063100] LNet: Removed LNI 192.168.204.42@tcp [ 9521.956284] Key type .llcrypt unregistered [ 9521.961665] Key type ._llcrypt unregistered [ 9540.651778] Key type ._llcrypt registered [ 9540.661497] Key type .llcrypt registered [ 9542.078420] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 23:39:36 (1787715576) [ 9562.570779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9562.598296] alg: No test for adler32 (adler32-zlib) [ 9563.757718] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 9564.029944] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 9565.720981] Key type lgssc registered [ 9567.422100] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9660.218139] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9666.688325] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9694.233410] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9844.626892] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 9849.626680] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9859.984975] Lustre: Unmounted lustre-client [ 9860.107901] LNetError: 150365:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5493:46ff:fedb:e482/1023: rc = -22 [ 9860.117840] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9860.443327] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 9860.674539] Lustre: Unmounted lustre-client [ 9861.601446] LNetError: 150368:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5493:46ff:fedb:e482/1023: rc = -22 [ 9861.621750] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9861.942789] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 9861.948376] Lustre: Skipped 1 previous similar message [ 9862.183037] Lustre: Unmounted lustre-client [ 9862.184759] Lustre: Skipped 1 previous similar message [ 9864.001692] LNetError: 150368:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5493:46ff:fedb:e482/1023: rc = -22 [ 9864.016385] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9864.327968] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 9864.337446] Lustre: Skipped 3 previous similar messages [ 9864.587978] Lustre: Unmounted lustre-client [ 9864.591924] Lustre: Skipped 3 previous similar messages [ 9865.319671] Lustre: 156312: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 [ 9865.984115] Lustre: 156344: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 [ 9874.362485] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 23:45:09 (1787715909) [ 9916.320680] LNet: 1 local NIs in recovery (showing 1): 192.168.204.42@tcp [10015.744235] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10023.182892] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10047.405667] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10119.582336] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10125.011215] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10144.715831] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10150.241169] LustreError: 161936:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [10203.040302] LNet: 1 local NIs in recovery (showing 1): 192.168.204.42@tcp [10274.721744] LNet: 1 local NIs in recovery (showing 1): 192.168.204.42@tcp [10289.120674] 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 [10289.146769] Lustre: Unmounted lustre-client [10289.151900] Lustre: Skipped 3 previous similar messages [10289.160966] LustreError: 161936:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [10317.722322] Key type lgssc unregistered [10335.136251] LNet: 1 local NIs in recovery (showing 1): 192.168.204.42@tcp [10335.401054] LNet: 162395:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10335.418745] LNetError: 162395:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10405.098358] LNet: Removed LNI 192.168.204.42@tcp [10405.908472] Key type .llcrypt unregistered [10405.910435] Key type ._llcrypt unregistered [10422.322420] Key type ._llcrypt registered [10422.324917] Key type .llcrypt registered [10423.475970] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 23:54:18 (1787716458) [10442.682713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10442.718033] alg: No test for adler32 (adler32-zlib) [10443.768245] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [10444.030117] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [10445.747522] Key type lgssc registered [10446.971306] Lustre: Echo OBD driver; http://www.lustre.org/ [10587.586383] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [10593.009371] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10613.228184] Lustre: lustre-OST0000-osc-ffff8a8a834ab000: disconnect after 23s idle [10614.398550] Lustre: Unmounted lustre-client [10787.058810] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [10792.690068] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10800.108292] Lustre: Unmounted lustre-client [10849.821216] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 00:01:24 (1787716884) [10948.241459] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10954.278689] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10979.577710] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11107.879315] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [11112.859753] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11128.191387] Lustre: Unmounted lustre-client [11266.406979] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [11271.839398] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11292.129212] Lustre: lustre-OST0001-osc-ffff8a8a8a1f8000: disconnect after 23s idle [11292.135052] Lustre: Skipped 1 previous similar message [11293.674734] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 00:08:48 (1787717328) [11296.099770] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11306.393047] Lustre: Unmounted lustre-client [11477.360903] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [11483.049898] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11493.508785] Lustre: Unmounted lustre-client [11494.126877] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [11504.426929] Lustre: Unmounted lustre-client [11506.581878] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 00:12:21 (1787717541) [11575.720835] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11581.909285] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11608.588283] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11609.565768] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [11701.735177] Lustre: lustre-MDT0000-mdc-ffff8a8a82c73000: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [11706.871152] LustreError: lustre-OST0001-osc-ffff8a8a82c73000: operation ost_connect to node 192.168.204.142@tcp failed: rc = -19 [11717.102527] LustreError: MGC192.168.204.142@tcp: Connection to MGS (at 192.168.204.142@tcp) was lost; in progress operations using this service will fail [11717.105129] LustreError: lustre-MDT0000-mdc-ffff8a8a82c73000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11717.150978] Lustre: Evicted from MGS (at 192.168.204.142@tcp) after server handle changed from 0xe796e466cf9c4fef to 0xe796e466cf9c534c [11717.153208] Lustre: lustre-MDT0000-mdc-ffff8a8a82c73000: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [11717.184895] Lustre: Skipped 1 previous similar message [11775.511676] Lustre: Unmounted lustre-client [11812.196622] Key type lgssc unregistered [11812.642562] LNet: 189939:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11812.653209] LNetError: 189939:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11813.763635] LNet: Removed LNI 192.168.204.42@tcp [11814.985129] Key type .llcrypt unregistered [11814.989589] Key type ._llcrypt unregistered [11826.432891] Key type ._llcrypt registered [11826.437095] Key type .llcrypt registered [11828.292046] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 00:17:42 (1787717862) [11831.028036] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11831.059175] alg: No test for adler32 (adler32-zlib) [11832.113857] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [11832.290646] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [11834.000270] Key type lgssc registered [11835.123519] Lustre: Echo OBD driver; http://www.lustre.org/ [11892.992166] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11900.145477] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11926.364902] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11932.787206] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [12028.931165] LustreError: lustre-OST0001-osc-ffff8a8a89d52800: operation ost_connect to node 192.168.204.142@tcp failed: rc = -19 [12159.816599] Lustre: Unmounted lustre-client [12197.081180] Key type lgssc unregistered [12197.456989] LNet: 195968:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12197.468242] LNetError: 195968:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12197.520333] LNet: Removed LNI 192.168.204.42@tcp [12198.353717] Key type .llcrypt unregistered [12198.362249] Key type ._llcrypt unregistered [12214.522081] Key type ._llcrypt registered [12214.524542] Key type .llcrypt registered [12216.765766] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 00:24:10 (1787718250) [12253.609804] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12253.778832] alg: No test for adler32 (adler32-zlib) [12255.353326] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [12255.799653] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [12257.632684] Key type lgssc registered [12259.167916] Lustre: Echo OBD driver; http://www.lustre.org/ [12358.461314] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12367.379120] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12406.658051] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12455.654033] Key type lgssc unregistered [12456.261952] LNet: 200957:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12456.273743] LNetError: 200957:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12456.310883] LNet: Removed LNI 192.168.204.42@tcp [12457.676831] Key type .llcrypt unregistered [12457.682766] Key type ._llcrypt unregistered [12566.910885] Key type ._llcrypt registered [12566.943336] Key type .llcrypt registered [12567.639468] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12567.661347] alg: No test for adler32 (adler32-zlib) [12569.142667] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [12569.608932] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [12571.424299] Key type lgssc registered [12573.094569] Lustre: Echo OBD driver; http://www.lustre.org/ [12629.551693] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 00:31:03 (1787718663) [12675.849419] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12683.880948] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12717.085527] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12719.048711] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [12721.793765] Lustre: Unmounted lustre-client [12722.400726] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [12722.738592] Lustre: Unmounted lustre-client [12723.573991] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [12734.711267] Lustre: Unmounted lustre-client [12766.412570] Key type lgssc unregistered [12766.950882] LNet: 205940:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12766.984551] LNetError: 205940:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12767.020060] LNet: Removed LNI 192.168.204.42@tcp [12768.206550] Key type .llcrypt unregistered [12768.211526] Key type ._llcrypt unregistered [12783.491267] Key type ._llcrypt registered [12783.502679] Key type .llcrypt registered [12785.883835] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 00:33:39 (1787718819) [12791.104075] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12791.125471] alg: No test for adler32 (adler32-zlib) [12792.679496] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [12793.052739] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [12794.840318] Key type lgssc registered [12796.636078] Lustre: Echo OBD driver; http://www.lustre.org/ [12857.577768] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12864.752441] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12887.579904] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12889.430700] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [12899.996878] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12901.648928] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [12910.064931] Lustre: lustre-MDT0000-mdc-ffff8a8a996a7800: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [12925.456629] Lustre: lustre-MDT0001-mdc-ffff8a8a996a7800: Connection to lustre-MDT0001 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [12936.677175] Lustre: 206615:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787718956/real 1787718956] req@00000000789b1d14 x1874559058843904/t0(0) o400->MGC192.168.204.142@tcp@192.168.204.142@tcp:26/25 lens 224/224 e 0 to 1 dl 1787718972 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [12936.752266] LustreError: MGC192.168.204.142@tcp: Connection to MGS (at 192.168.204.142@tcp) was lost; in progress operations using this service will fail [12975.605508] Lustre: Evicted from MGS (at 192.168.204.142@tcp) after server handle changed from 0xd7bcd313540f1081 to 0xd7bcd313540f1225 [12975.625958] Lustre: MGC192.168.204.142@tcp: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [13015.529237] LustreError: lustre-MDT0000-mdc-ffff8a8a996a7800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [13015.558375] Lustre: lustre-MDT0000-mdc-ffff8a8a996a7800: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [13022.274878] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13024.465038] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [13031.461276] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13033.257723] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [13035.888804] Lustre: Unmounted lustre-client [13064.043711] Key type lgssc unregistered [13064.332660] LNet: 211486:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13064.345994] LNetError: 211486:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13064.364619] LNet: Removed LNI 192.168.204.42@tcp [13064.955977] Key type .llcrypt unregistered [13064.957947] Key type ._llcrypt unregistered [13093.265888] Key type ._llcrypt registered [13093.268540] Key type .llcrypt registered [13093.877247] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13093.896038] alg: No test for adler32 (adler32-zlib) [13094.922956] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [13095.107422] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [13096.850070] Key type lgssc registered [13098.936450] Lustre: Echo OBD driver; http://www.lustre.org/ [13207.498924] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13214.593775] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13239.017836] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13285.438982] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 00:41:59 (1787719319) [13410.030712] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13422.326648] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13455.142820] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13457.288323] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [13462.859370] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [13464.484762] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [13464.498563] Lustre: Skipped 1 previous similar message [13471.266837] Lustre: Unmounted lustre-client [13474.527381] Lustre: Unmounted lustre-client [13476.793967] Lustre: Unmounted lustre-client [13479.688503] Lustre: Unmounted lustre-client [13497.915331] Lustre: Unmounted lustre-client [13497.924826] Lustre: Skipped 1 previous similar message [13527.634919] Key type lgssc unregistered [13527.947518] LNet: 220662:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13527.963114] LNetError: 220662:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13527.985384] LNet: Removed LNI 192.168.204.42@tcp [13528.883035] Key type .llcrypt unregistered [13528.885718] Key type ._llcrypt unregistered [13544.615168] Key type ._llcrypt registered [13544.621390] Key type .llcrypt registered [13547.955736] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 00:46:20 (1787719580) [13563.660902] Key type .llcrypt unregistered [13563.669972] Key type ._llcrypt unregistered [13565.405415] Key type ._llcrypt registered [13565.407626] Key type .llcrypt registered [13565.854371] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [13565.891309] alg: No test for adler32 (adler32-zlib) [13567.096409] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [13567.363096] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [13569.176173] Key type lgssc registered [13571.734722] Lustre: Echo OBD driver; http://www.lustre.org/ [13603.122749] Key type lgssc unregistered [13603.379928] LNet: 222830:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13603.385745] LNetError: 222830:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13603.396051] LNet: Removed LNI 192.168.204.42@tcp [13604.154908] Key type .llcrypt unregistered [13604.156787] Key type ._llcrypt unregistered [13604.787566] Key type ._llcrypt registered [13604.795932] Key type .llcrypt registered [13606.140204] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 00:47:20 (1787719640) [13619.839539] Key type .llcrypt unregistered [13619.848327] Key type ._llcrypt unregistered [13621.600255] Key type ._llcrypt registered [13621.605572] Key type .llcrypt registered [13622.363187] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13622.397801] alg: No test for adler32 (adler32-zlib) [13623.653681] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [13624.206527] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [13626.000204] Key type lgssc registered [13628.522363] Lustre: Echo OBD driver; http://www.lustre.org/ [13650.993074] Key type lgssc unregistered [13651.276974] LNet: 224391:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13651.290139] LNetError: 224391:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13651.323851] LNet: Removed LNI 192.168.204.42@tcp [13652.509551] Key type .llcrypt unregistered [13652.512766] Key type ._llcrypt unregistered [13660.346274] Key type ._llcrypt registered [13660.351516] Key type .llcrypt registered [13660.588827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13660.608741] alg: No test for adler32 (adler32-zlib) [13662.008362] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [13662.447366] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [13664.361832] Key type lgssc registered [13666.661795] Lustre: Echo OBD driver; http://www.lustre.org/ [13702.784808] Key type lgssc unregistered [13703.446281] LNet: 226159:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13703.463067] LNetError: 226159:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13703.505363] LNet: Removed LNI 192.168.204.42@tcp [13705.158775] Key type .llcrypt unregistered [13705.167480] Key type ._llcrypt unregistered [13706.054734] Key type ._llcrypt registered [13706.064837] Key type .llcrypt registered [13708.836271] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 00:49:01 (1787719741) [13724.832538] Key type .llcrypt unregistered [13724.834292] Key type ._llcrypt unregistered [13726.298702] Key type ._llcrypt registered [13726.306365] Key type .llcrypt registered [13726.564711] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13726.591301] alg: No test for adler32 (adler32-zlib) [13728.202905] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [13728.729708] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [13730.630848] Key type lgssc registered [13732.131453] Lustre: Echo OBD driver; http://www.lustre.org/ [13755.319654] Key type lgssc unregistered [13755.755286] LNet: 227716:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13755.768817] LNetError: 227716:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13755.788577] LNet: Removed LNI 192.168.204.42@tcp [13756.859568] Key type .llcrypt unregistered [13756.862122] Key type ._llcrypt unregistered [13767.811596] Key type ._llcrypt registered [13767.818404] Key type .llcrypt registered [13768.481478] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13768.495785] alg: No test for adler32 (adler32-zlib) [13769.618347] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [13769.923415] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [13771.640208] Key type lgssc registered [13772.960344] Lustre: Echo OBD driver; http://www.lustre.org/ [13795.878957] Key type lgssc unregistered [13796.311916] LNet: 229025:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13796.324713] LNetError: 229025:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13796.347602] LNet: Removed LNI 192.168.204.42@tcp [13797.073771] Key type .llcrypt unregistered [13797.076621] Key type ._llcrypt unregistered [13806.529840] Key type ._llcrypt registered [13806.546906] Key type .llcrypt registered [13807.350068] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13807.397649] alg: No test for adler32 (adler32-zlib) [13808.747353] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [13809.228531] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [13811.192301] Key type lgssc registered [13813.110914] Lustre: Echo OBD driver; http://www.lustre.org/ [13830.860754] Key type lgssc unregistered [13831.084743] LNet: 230334:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13831.101548] LNetError: 230334:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13831.115310] LNet: Removed LNI 192.168.204.42@tcp [13831.907774] Key type .llcrypt unregistered [13831.912526] Key type ._llcrypt unregistered [13840.348045] Key type ._llcrypt registered [13840.354862] Key type .llcrypt registered [13841.020200] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13841.059440] alg: No test for adler32 (adler32-zlib) [13842.559831] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [13843.188548] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [13845.040263] Key type lgssc registered [13847.134656] Lustre: Echo OBD driver; http://www.lustre.org/ [13882.613108] Key type lgssc unregistered [13883.021968] LNet: 232107:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13883.033945] LNetError: 232107:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13883.062263] LNet: Removed LNI 192.168.204.42@tcp [13883.791992] Key type .llcrypt unregistered [13883.807461] Key type ._llcrypt unregistered [13884.412501] Key type ._llcrypt registered [13884.417214] Key type .llcrypt registered [13886.384827] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 00:52:00 (1787719920) [13905.469524] Key type .llcrypt unregistered [13905.476449] Key type ._llcrypt unregistered [13907.040244] Key type ._llcrypt registered [13907.042878] Key type .llcrypt registered [13907.314241] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13907.332723] alg: No test for adler32 (adler32-zlib) [13908.786462] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [13909.255135] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [13911.104283] Key type lgssc registered [13914.079392] Lustre: Echo OBD driver; http://www.lustre.org/ [13935.926826] Key type lgssc unregistered [13936.198891] LNet: 233668:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13936.213119] LNetError: 233668:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13936.233727] LNet: Removed LNI 192.168.204.42@tcp [13937.476249] Key type .llcrypt unregistered [13937.491995] Key type ._llcrypt unregistered [13948.162869] Key type ._llcrypt registered [13948.165944] Key type .llcrypt registered [13948.849640] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13948.863914] alg: No test for adler32 (adler32-zlib) [13949.985065] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [13950.323353] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [13952.064197] Key type lgssc registered [13953.684316] Lustre: Echo OBD driver; http://www.lustre.org/ [13977.983654] Key type lgssc unregistered [13978.222573] LNet: 235003:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13978.233306] LNetError: 235003:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13978.269435] LNet: Removed LNI 192.168.204.42@tcp [13979.638401] Key type .llcrypt unregistered [13979.643913] Key type ._llcrypt unregistered [13988.852109] Key type ._llcrypt registered [13988.858299] Key type .llcrypt registered [13989.390520] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13989.408574] alg: No test for adler32 (adler32-zlib) [13990.708188] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [13991.000637] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [13992.757655] Key type lgssc registered [13994.617373] Lustre: Echo OBD driver; http://www.lustre.org/ [14017.014197] Key type lgssc unregistered [14017.476804] LNet: 236314:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14017.488445] LNetError: 236314:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14017.512430] LNet: Removed LNI 192.168.204.42@tcp [14018.604987] Key type .llcrypt unregistered [14018.614097] Key type ._llcrypt unregistered [14028.887162] Key type ._llcrypt registered [14028.889714] Key type .llcrypt registered [14029.064101] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14029.073564] alg: No test for adler32 (adler32-zlib) [14030.217573] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [14030.445868] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [14032.368203] Key type lgssc registered [14034.546863] Lustre: Echo OBD driver; http://www.lustre.org/ [14055.354974] Key type lgssc unregistered [14055.723935] LNet: 237626:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14055.733536] LNetError: 237626:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14055.748347] LNet: Removed LNI 192.168.204.42@tcp [14056.790451] Key type .llcrypt unregistered [14056.797226] Key type ._llcrypt unregistered [14067.050971] Key type ._llcrypt registered [14067.065424] Key type .llcrypt registered [14067.505975] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14067.542387] alg: No test for adler32 (adler32-zlib) [14068.762580] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [14069.108309] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [14070.840371] Key type lgssc registered [14072.308850] Lustre: Echo OBD driver; http://www.lustre.org/ [14096.955245] Key type lgssc unregistered [14097.520426] LNet: 238939:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14097.533733] LNetError: 238939:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14097.586930] LNet: Removed LNI 192.168.204.42@tcp [14099.278777] Key type .llcrypt unregistered [14099.280894] Key type ._llcrypt unregistered [14109.165347] Key type ._llcrypt registered [14109.181967] Key type .llcrypt registered [14109.868221] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14109.902727] alg: No test for adler32 (adler32-zlib) [14111.268840] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [14111.668693] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [14113.488921] Key type lgssc registered [14115.448507] Lustre: Echo OBD driver; http://www.lustre.org/ [14137.241297] Key type lgssc unregistered [14137.618762] LNet: 240248:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14137.626700] LNetError: 240248:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14137.641462] LNet: Removed LNI 192.168.204.42@tcp [14138.510607] Key type .llcrypt unregistered [14138.512366] Key type ._llcrypt unregistered [14168.706726] Key type ._llcrypt registered [14168.709284] Key type .llcrypt registered [14170.384848] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 00:56:44 (1787720204) [14184.482432] Key type .llcrypt unregistered [14184.484104] Key type ._llcrypt unregistered [14186.135973] Key type ._llcrypt registered [14186.141648] Key type .llcrypt registered [14186.687698] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14186.723810] alg: No test for adler32 (adler32-zlib) [14187.954977] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [14188.288649] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [14189.984851] Key type lgssc registered [14191.644807] Lustre: Echo OBD driver; http://www.lustre.org/ [14212.199472] Key type lgssc unregistered [14212.568780] LNet: 242583:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14212.584861] LNetError: 242583:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14212.611789] LNet: Removed LNI 192.168.204.42@tcp [14213.294736] Key type .llcrypt unregistered [14213.296675] Key type ._llcrypt unregistered [14222.112441] Key type ._llcrypt registered [14222.122189] Key type .llcrypt registered [14222.548893] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14222.561540] alg: No test for adler32 (adler32-zlib) [14223.739708] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [14224.173500] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [14225.903655] Key type lgssc registered [14227.557736] Lustre: Echo OBD driver; http://www.lustre.org/ [14247.322887] Key type lgssc unregistered [14247.596314] LNet: 243909:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14247.601087] LNetError: 243909:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14247.611718] LNet: Removed LNI 192.168.204.42@tcp [14248.391762] Key type .llcrypt unregistered [14248.397521] Key type ._llcrypt unregistered [14257.254882] Key type ._llcrypt registered [14257.267975] Key type .llcrypt registered [14257.764566] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14257.794727] alg: No test for adler32 (adler32-zlib) [14258.999609] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [14259.320122] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [14261.005239] Key type lgssc registered [14262.272893] Lustre: Echo OBD driver; http://www.lustre.org/ [14281.672947] Key type lgssc unregistered [14282.068720] LNet: 245223:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14282.077991] LNetError: 245223:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14282.098983] LNet: Removed LNI 192.168.204.42@tcp [14282.913824] Key type .llcrypt unregistered [14282.923088] Key type ._llcrypt unregistered [14291.701203] Key type ._llcrypt registered [14291.794900] Key type .llcrypt registered [14292.159513] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14292.180757] alg: No test for adler32 (adler32-zlib) [14293.413941] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [14293.802864] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [14295.512327] Key type lgssc registered [14296.966403] Lustre: Echo OBD driver; http://www.lustre.org/ [14331.064970] Key type lgssc unregistered [14331.376599] LNet: 247021:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14331.401702] LNetError: 247021:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14331.424419] LNet: Removed LNI 192.168.204.42@tcp [14332.815118] Key type .llcrypt unregistered [14332.816926] Key type ._llcrypt unregistered [14333.587076] Key type ._llcrypt registered [14333.590549] Key type .llcrypt registered [14336.186210] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 00:59:29 (1787720369) [14361.374111] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14361.455944] alg: No test for adler32 (adler32-zlib) [14362.920146] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [14363.290735] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [14365.064596] Key type lgssc registered [14366.557161] Lustre: Echo OBD driver; http://www.lustre.org/ [14629.905357] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 01:04:24 (1787720664) [14631.697089] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [14702.437121] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14374 sec ========= 01:05:36 (1787720736) [14704.612588] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 01:05:38 (1787720738) === [14709.229868] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 01:05:43 (1787720743) === [14747.166314] Key type lgssc unregistered [14747.666902] LNet: 256109:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14747.675865] LNetError: 256109:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14747.688601] LNet: Removed LNI 192.168.204.42@tcp [14748.557792] Key type .llcrypt unregistered [14748.560910] Key type ._llcrypt unregistered