[ 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-8.fc42 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 727529887 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 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 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.001015] APIC: Switch to symmetric I/O mode setup [ 0.003219] x2apic enabled [ 0.004006] Switched APIC routing to physical x2apic. [ 0.005014] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008026] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009016] pid_max: default: 32768 minimum: 301 [ 0.011156] LSM: Security Framework initializing [ 0.012055] Yama: becoming mindful. [ 0.013037] SELinux: Initializing. [ 0.015017] *** VALIDATE selinux *** [ 0.023010] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027742] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028161] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030119] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032096] *** VALIDATE tmpfs *** [ 0.033520] *** VALIDATE proc *** [ 0.034298] *** VALIDATE cgroup *** [ 0.035013] *** VALIDATE cgroup2 *** [ 0.036318] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038014] 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.041033] Spectre V2 : User space: Vulnerable [ 0.042011] Speculative Store Bypass: Vulnerable [ 0.045381] debug: unmapping init [mem 0xffffffffa9c59000-0xffffffffa9c60fff] [ 0.047281] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048729] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049031] ... version: 2 [ 0.050012] ... bit width: 48 [ 0.051013] ... generic registers: 4 [ 0.052013] ... value mask: 0000ffffffffffff [ 0.053014] ... max period: 00007fffffffffff [ 0.054017] ... fixed-purpose events: 3 [ 0.055013] ... event mask: 000000070000000f [ 0.056427] rcu: Hierarchical SRCU implementation. [ 0.058827] smp: Bringing up secondary CPUs ... [ 0.059793] x86: Booting SMP configuration: [ 0.060034] .... node #0, CPUs: #1 #2 #3 [ 0.074595] smp: Brought up 1 node, 4 CPUs [ 0.076015] smpboot: Max logical packages: 1 [ 0.077023] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.156303] node 0 deferred pages initialised in 77ms [ 0.162298] devtmpfs: initialized [ 0.165074] x86/mm: Memory block size: 128MB [ 0.168572] gcov: version magic: 0x41383552 [ 0.171000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.172095] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.173389] pinctrl core: initialized pinctrl subsystem [ 0.174352] [ 0.174951] ************************************************************* [ 0.175022] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.176028] ** ** [ 0.177035] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.178022] ** ** [ 0.179025] ** This means that this kernel is built to expose internal ** [ 0.180022] ** IOMMU data structures, which may compromise security on ** [ 0.181029] ** your system. ** [ 0.182021] ** ** [ 0.183020] ** If you see this message and you are not debugging the ** [ 0.184022] ** kernel, report this immediately to your vendor! ** [ 0.185024] ** ** [ 0.186225] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.187016] ************************************************************* [ 0.189210] NET: Registered protocol family 16 [ 0.190882] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.191085] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.192076] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.194170] cpuidle: using governor menu [ 0.196415] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.198803] PCI: Using configuration type 1 for base access [ 0.199239] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.209082] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.212029] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.218276] cryptd: max_cpu_qlen set to 1000 [ 0.220000] ACPI: Added _OSI(Module Device) [ 0.220000] ACPI: Added _OSI(Processor Device) [ 0.221020] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.224023] ACPI: Added _OSI(Processor Aggregator Device) [ 0.239933] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.249152] ACPI: Interpreter enabled [ 0.250076] ACPI: PM: (supports S0 S3 S4 S5) [ 0.251019] ACPI: Using IOAPIC for interrupt routing [ 0.252132] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.253641] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.264701] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.265054] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.266023] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.267103] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.269643] acpiphp: Slot [2] registered [ 0.270172] acpiphp: Slot [5] registered [ 0.271156] acpiphp: Slot [6] registered [ 0.272153] acpiphp: Slot [3] registered [ 0.273138] acpiphp: Slot [4] registered [ 0.274169] acpiphp: Slot [7] registered [ 0.275178] acpiphp: Slot [8] registered [ 0.276176] acpiphp: Slot [9] registered [ 0.277422] acpiphp: Slot [10] registered [ 0.278125] acpiphp: Slot [11] registered [ 0.279199] acpiphp: Slot [12] registered [ 0.280172] acpiphp: Slot [13] registered [ 0.281130] acpiphp: Slot [14] registered [ 0.282157] acpiphp: Slot [15] registered [ 0.283225] acpiphp: Slot [16] registered [ 0.284105] acpiphp: Slot [17] registered [ 0.286062] acpiphp: Slot [18] registered [ 0.288926] acpiphp: Slot [19] registered [ 0.289155] acpiphp: Slot [20] registered [ 0.290545] acpiphp: Slot [21] registered [ 0.291131] acpiphp: Slot [22] registered [ 0.292167] acpiphp: Slot [23] registered [ 0.294033] acpiphp: Slot [24] registered [ 0.295160] acpiphp: Slot [25] registered [ 0.296285] acpiphp: Slot [26] registered [ 0.297244] acpiphp: Slot [27] registered [ 0.298164] acpiphp: Slot [28] registered [ 0.299160] acpiphp: Slot [29] registered [ 0.300120] acpiphp: Slot [30] registered [ 0.301119] acpiphp: Slot [31] registered [ 0.302077] PCI host bridge to bus 0000:00 [ 0.303029] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.304032] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.305033] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.306038] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.307035] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.308051] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.309434] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.312864] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.315598] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.319710] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.321577] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.322024] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.323040] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.324023] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.326000] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.328087] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.329055] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.331344] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.333719] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.340021] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.342022] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.348000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.351023] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.354024] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.359797] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.364993] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.367951] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.370039] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.376017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.383161] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.384427] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.385391] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.386340] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.387326] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.392240] iommu: Default domain type: Passthrough [ 0.393475] SCSI subsystem initialized [ 0.394197] ACPI: bus type USB registered [ 0.395156] usbcore: registered new interface driver usbfs [ 0.396104] usbcore: registered new interface driver hub [ 0.397090] usbcore: registered new device driver usb [ 0.398159] pps_core: LinuxPPS API ver. 1 registered [ 0.399012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.400102] PTP clock support registered [ 0.401387] EDAC MC: Ver: 3.0.0 [ 0.402419] PCI: Using ACPI for IRQ routing [ 0.406007] NetLabel: Initializing [ 0.407011] NetLabel: domain hash size = 128 [ 0.409012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.411570] NetLabel: unlabeled traffic allowed by default [ 0.414355] vgaarb: loaded [ 0.416040] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.418013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.427426] clocksource: Switched to clocksource kvm-clock [ 0.622967] VFS: Disk quotas dquot_6.6.0 [ 0.626671] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.630993] *** VALIDATE ramfs *** [ 0.633264] *** VALIDATE hugetlbfs *** [ 0.635919] pnp: PnP ACPI init [ 0.640574] pnp: PnP ACPI: found 6 devices [ 0.666583] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.672648] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.677230] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.682642] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.688025] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.693654] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.700366] NET: Registered protocol family 2 [ 0.706816] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.719445] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.727814] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.737830] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.743943] TCP: Hash tables configured (established 65536 bind 65536) [ 0.750975] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.759603] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.765693] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.771457] NET: Registered protocol family 1 [ 0.776729] RPC: Registered named UNIX socket transport module. [ 0.782253] RPC: Registered udp transport module. [ 0.784897] RPC: Registered tcp transport module. [ 0.788966] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.793483] NET: Registered protocol family 44 [ 0.796782] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.801538] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.805783] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.809915] PCI: CLS 0 bytes, default 64 [ 0.814505] Unpacking initramfs... [ 3.588302] debug: unmapping init [mem 0xffff9b7cfcc64000-0xffff9b7cfffcffff] [ 3.592832] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.595110] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 3.598209] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 4.582951] Initialise system trusted keyrings [ 4.605854] Key type blacklist registered [ 4.607327] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 4.615582] zbud: loaded [ 4.619571] *** VALIDATE nfs *** [ 4.620337] *** VALIDATE nfs4 *** [ 4.621412] pstore: using deflate compression [ 4.624111] Platform Keyring initialized [ 4.728956] NET: Registered protocol family 38 [ 4.731285] Key type asymmetric registered [ 4.732675] Asymmetric key parser 'x509' registered [ 4.734914] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.738826] io scheduler mq-deadline registered [ 4.740916] io scheduler kyber registered [ 4.743331] io scheduler bfq registered [ 4.745592] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.749727] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.753840] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.757706] ACPI: Power Button [PWRF] [ 4.764704] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.776902] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.788325] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.827623] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.868640] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.880528] Non-volatile memory driver v1.3 [ 4.886165] Linux agpgart interface v0.103 [ 5.045415] virtio_blk virtio1: [vda] 133944 512-byte logical blocks (68.6 MB/65.4 MiB) [ 5.052843] vda: detected capacity change from 0 to 68579328 [ 5.120428] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 5.129135] vdb: detected capacity change from 0 to 1073741824 [ 5.145548] libphy: Fixed MDIO Bus: probed [ 5.156268] usbcore: registered new interface driver usbserial_generic [ 5.158793] usbserial: USB Serial support registered for generic [ 5.161468] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 5.166217] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 5.170214] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 5.175836] mousedev: PS/2 mouse device common for all mice [ 5.179898] rtc_cmos 00:05: RTC can wake from S4 [ 5.184909] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 5.192817] rtc_cmos 00:05: registered as rtc0 [ 5.199582] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 5.199917] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 5.203288] intel_pstate: CPU model not supported [ 5.208159] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 5.217579] hid: raw HID events driver (C) Jiri Kosina [ 5.223589] usbcore: registered new interface driver usbhid [ 5.225743] usbhid: USB HID core driver [ 5.227389] drop_monitor: Initializing network drop monitor service [ 5.229830] Initializing XFRM netlink socket [ 5.231963] NET: Registered protocol family 10 [ 5.235082] Segment Routing with IPv6 [ 5.236229] NET: Registered protocol family 17 [ 5.238586] mpls_gso: MPLS GSO support [ 5.245270] RAS: Correctable Errors collector initialized. [ 5.247302] AVX version of gcm_enc/dec engaged. [ 5.248775] AES CTR mode by8 optimization enabled [ 5.337405] sched_clock: Marking stable (5337384256, 0)->(7422828491, -2085444235) [ 5.341362] registered taskstats version 1 [ 5.343379] Loading compiled-in X.509 certificates [ 5.344839] zswap: loaded using pool lzo/zbud [ 5.372760] Key type big_key registered [ 5.392750] Key type encrypted registered [ 5.394435] ima: No TPM chip found, activating TPM-bypass! [ 5.396110] ima: Allocated hash algorithm: sha1 [ 5.397351] ima: No architecture policies found [ 5.398513] evm: Initialising EVM extended attributes: [ 5.399713] evm: security.selinux [ 5.400564] evm: security.ima [ 5.401304] evm: security.capability [ 5.401996] evm: HMAC attrs: 0x1 [ 5.404218] rtc_cmos 00:05: setting system clock to 2026-04-23 04:39:19 UTC (1776919159) [ 5.410664] debug: unmapping init [mem 0xffffffffaac03000-0xffffffffaadfffff] [ 5.412864] debug: unmapping init [mem 0xffffffffa9982000-0xffffffffa9c58fff] [ 5.422239] Write protecting the kernel read-only data: 28672k [ 5.425536] debug: unmapping init [mem 0xffffffffa8003000-0xffffffffa81fffff] [ 5.428301] debug: unmapping init [mem 0xffffffffa8914000-0xffffffffa89fffff] [ 5.461478] 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) [ 5.469527] systemd[1]: Detected virtualization kvm. [ 5.471363] systemd[1]: Detected architecture x86-64. [ 5.472906] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.503618] systemd[1]: No hostname configured. [ 5.508228] systemd[1]: Set hostname to . [ 5.513214] random: systemd: uninitialized urandom read (16 bytes read) [ 5.519491] systemd[1]: Initializing machine ID from random generator. [ 5.618828] random: ln: uninitialized urandom read (6 bytes read) [ 6.381466] random: systemd: uninitialized urandom read (16 bytes read) [ 6.384679] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 6.398903] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 6.405651] systemd[1]: Reached target Local Encrypted Volumes. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Paths. [ OK ] Reached target Slices. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Swap. Starting Journal Service... [ OK ] Reached target Sockets. Starting Apply Kernel Variables... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ 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... [ 9.504542] device-mapper: uevent: version 1.0.3 [ 9.507493] 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. [ 12.121284] virtio_net virtio0 ens2: renamed from eth0 [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 12.247585] random: fast init done [ 12.956419] scsi host0: ata_piix [ 13.216371] scsi host1: ata_piix [ 13.224545] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 13.232727] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 17.942931] random: crng init done [ 17.950213] random: 7 urandom warning(s) missed due to ratelimiting [ 20.723257] dracut-initqueue[585]: 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. [ 22.865209] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target 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 target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 26.206146] printk: systemd: 26 output lines suppressed due to ratelimiting [ 27.458152] SELinux: Disabled at runtime. [ 27.642798] 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) [ 27.670609] systemd[1]: Detected virtualization kvm. [ 27.674651] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 29.590368] systemd[1]: initrd-switch-root.service: Succeeded. [ 29.608782] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 29.637583] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 29.655452] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 29.670484] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 29.688213] systemd[1]: Starting Journal Service... Starting Journal Service... [ 29.728755] systemd[1]: Listening on Process Core Dump Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. [ OK ] Stopped target Switch Root. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting Huge Pages File System... [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Remount Root and Kernel File Systems... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Stopped target Initrd Root File System. [ OK ] Reached target Slices. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Mounting Kernel Debug File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Created slice system-getty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ 30.564722] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 31.451132] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 32.746205] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 32.774637] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 33.207795] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 33.310534] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit)[ 37.719944] Key type dns_resolver registered [** ] A start job is running for Configur…-only root support (8s / no limit)[ 38.543110] NFS: Registering the id_resolver key type [ 38.546338] Key type id_resolver registered [ 38.548810] Key type id_legacy registered [*** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started dnf makecache --timer. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started Login Service. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... 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 oleg313-client login: [ 83.666038] libcfs: loading out-of-tree module taints kernel. [ 83.680951] Key type ._llcrypt registered [ 83.682641] Key type .llcrypt registered [ 84.102667] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 84.113635] alg: No test for adler32 (adler32-zlib) [ 85.218711] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 85.643605] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 87.319208] Key type lgssc registered [ 88.328237] Lustre: Echo OBD driver; http://www.lustre.org/ [ 183.014337] Lustre: Mounted lustre-client [ 186.221313] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 201.018619] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing check_logdir /tmp/testlogs/ [ 203.526147] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing yml_node [ 206.478664] Lustre: DEBUG MARKER: Client: 2.17.51.74 [ 207.982135] Lustre: DEBUG MARKER: MDS: 2.17.51.74 [ 208.864157] Lustre: lustre-OST0000-osc-ffff9b7d46ce1000: disconnect after 23s idle [ 209.513526] Lustre: DEBUG MARKER: OSS: 2.17.51.74 [ 210.492780] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Apr 23 00:42:43 EDT 2026 [ 221.868874] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 222.815613] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 224.183482] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 234.501291] Lustre: Unmounted lustre-client [ 322.782255] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 324.545478] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 334.797495] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 359.942097] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 00:45:13 (1776919513) [ 381.842760] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 383.748082] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 407.966827] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid [ 437.228379] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 439.384293] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 463.142794] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid [ 478.948732] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 00:47:12 (1776919632) [ 532.333386] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 534.031230] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 544.240621] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 544.612722] Lustre: Mounted lustre-client [ 555.883898] LustreError: 21056:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d50d15800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 556.170642] Lustre: Unmounted lustre-client [ 584.124132] Key type lgssc unregistered [ 584.274553] LNet: 21459:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 584.278284] LNetError: 21459:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 584.286094] LNet: Removed LNI 192.168.203.13@tcp [ 584.642128] Key type .llcrypt unregistered [ 584.644072] Key type ._llcrypt unregistered [ 592.214922] Key type ._llcrypt registered [ 592.216529] Key type .llcrypt registered [ 593.028349] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 00:49:06 (1776919746) [ 594.141788] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 594.158837] alg: No test for adler32 (adler32-zlib) [ 595.092511] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 595.204830] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 596.815733] Key type lgssc registered [ 597.640569] Lustre: Echo OBD driver; http://www.lustre.org/ [ 623.035141] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 624.991080] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 634.174828] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 641.013907] Lustre: Mounted lustre-client [ 651.143873] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 00:50:04 (1776919804) [ 656.591230] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 00:50:09 (1776919809) [ 661.205368] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 00:50:14 (1776919814) [ 667.234097] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 00:50:20 (1776919820) [ 679.740462] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 00:50:33 (1776919833) [ 686.754909] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 00:50:40 (1776919840) [ 693.219597] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 00:50:46 (1776919846) [ 696.912131] LustreError: 29258:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d45d0c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 696.961960] Lustre: Unmounted lustre-client [ 697.180979] Lustre: Mounted lustre-client [ 697.990308] Lustre: Unmounted lustre-client [ 716.501589] Key type lgssc unregistered [ 716.629374] LNet: 29729:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 716.634470] LNetError: 29729:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 716.649695] LNet: Removed LNI 192.168.203.13@tcp [ 716.919565] Key type .llcrypt unregistered [ 716.921320] Key type ._llcrypt unregistered [ 729.328624] Key type ._llcrypt registered [ 729.330325] Key type .llcrypt registered [ 729.571925] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 729.582407] alg: No test for adler32 (adler32-zlib) [ 730.487396] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 730.626715] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 732.256151] Key type lgssc registered [ 732.893774] Lustre: Echo OBD driver; http://www.lustre.org/ [ 769.741641] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 771.266114] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 782.059312] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 809.244463] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 00:52:42 (1776919962) [ 826.723152] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 829.010597] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 840.169466] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 841.575583] Lustre: Mounted lustre-client [ 910.791351] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 00:54:24 (1776920064) [ 931.333262] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 00:54:44 (1776920084) [ 936.192520] LustreError: 38547:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d49158800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 936.230102] Lustre: Unmounted lustre-client [ 979.932973] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 981.538652] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 990.616207] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 996.371150] Lustre: Mounted lustre-client [ 999.648956] LustreError: 40331:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d4a7ca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 999.727577] Lustre: Unmounted lustre-client [ 1029.153794] Key type lgssc unregistered [ 1029.440799] LNet: 40736:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1029.445644] LNetError: 40736:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1029.458727] LNet: Removed LNI 192.168.203.13@tcp [ 1029.977432] Key type .llcrypt unregistered [ 1029.982324] Key type ._llcrypt unregistered [ 1045.880767] Key type ._llcrypt registered [ 1045.884637] Key type .llcrypt registered [ 1047.374245] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 00:56:39 (1776920199) [ 1049.714011] hrtimer: interrupt took 8588654 ns [ 1051.067206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1051.105388] alg: No test for adler32 (adler32-zlib) [ 1052.455821] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 1052.819822] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 1054.583528] Key type lgssc registered [ 1056.038556] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1099.937396] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1102.732277] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1115.992216] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1127.956919] Lustre: Mounted lustre-client [ 1137.651644] LustreError: 43913:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d5084d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1137.704770] Lustre: Unmounted lustre-client [ 1199.725443] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1202.330228] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1213.980115] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1219.585520] Lustre: Mounted lustre-client [ 1221.960763] LustreError: 45700:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d4b223800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1222.007170] Lustre: Unmounted lustre-client [ 1240.779768] Key type lgssc unregistered [ 1240.921779] LNet: 46102:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1240.925745] LNetError: 46102:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1240.935829] LNet: Removed LNI 192.168.203.13@tcp [ 1241.221324] Key type .llcrypt unregistered [ 1241.223308] Key type ._llcrypt unregistered [ 1247.774844] Key type ._llcrypt registered [ 1247.780141] Key type .llcrypt registered [ 1248.529609] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 01:00:01 (1776920401) [ 1249.665041] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1249.674769] alg: No test for adler32 (adler32-zlib) [ 1250.576183] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 1250.681858] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 1252.279565] Key type lgssc registered [ 1252.899895] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1276.252945] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1277.975551] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1287.122822] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1294.823128] Lustre: Mounted lustre-client [ 1350.297241] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 01:01:43 (1776920503) [ 1351.018194] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 1351.892606] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 01:01:45 (1776920505) [ 1352.572746] LustreError: 50944:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d50d16000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1352.615960] Lustre: Unmounted lustre-client [ 1373.373805] Key type lgssc unregistered [ 1373.519622] LNet: 51346:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1373.524900] LNetError: 51346:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1373.535955] LNet: Removed LNI 192.168.203.13@tcp [ 1373.880890] Key type .llcrypt unregistered [ 1373.882768] Key type ._llcrypt unregistered [ 1388.033209] Key type ._llcrypt registered [ 1388.035393] Key type .llcrypt registered [ 1388.179401] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1388.193425] alg: No test for adler32 (adler32-zlib) [ 1389.086542] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 1389.184234] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 1390.783306] Key type lgssc registered [ 1391.527139] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1415.051756] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 01:02:48 (1776920568) [ 1421.739836] Key type lgssc unregistered [ 1421.927539] LNet: 53738:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1421.933217] LNetError: 53738:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1421.947735] LNet: Removed LNI 192.168.203.13@tcp [ 1422.322598] Key type .llcrypt unregistered [ 1422.324646] Key type ._llcrypt unregistered [ 1428.160871] Key type ._llcrypt registered [ 1428.163237] Key type .llcrypt registered [ 1428.419626] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1428.426846] alg: No test for adler32 (adler32-zlib) [ 1429.328219] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 1429.433480] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 1431.039163] Key type lgssc registered [ 1431.688415] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1457.006585] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1458.913976] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1467.826508] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1473.527143] Lustre: Mounted lustre-client [ 1498.947512] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 01:04:12 (1776920652) [ 1499.696694] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 1500.416909] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 01:04:13 (1776920653) [ 1500.775094] LustreError: 57341:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d50fc0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1500.809164] Lustre: Unmounted lustre-client [ 1535.816490] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1537.468903] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1568.867519] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 01:05:22 (1776920722) [ 1608.689974] Lustre: Mounted lustre-client [ 1611.362144] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1624.037478] Lustre: lustre-MDT0001-mdc-ffff9b7d457b0000: Connection to lustre-MDT0001 (at 192.168.203.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1633.071722] LustreError: 62296:0:(obd_config.c:2101:class_config_llog_handler()) MGC192.168.203.113@tcp: cfg command failed: rc = -17 [ 1633.075441] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 1633.075441] [ 1633.079204] LustreError: 61501:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 1634.271416] Lustre: lustre-OST0000-osc-ffff9b7d457b0000: disconnect after 24s idle [ 1636.414318] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID ========================================================== 01:06:29 (1776920789) [ 1657.389621] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1711.155735] LustreError: 66090:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d457b0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1711.410099] Lustre: Unmounted lustre-client [ 1782.783249] Lustre: Mounted lustre-client [ 1785.141928] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1807.552489] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 01:09:21 (1776920961) [ 1807.826968] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1835.959082] Lustre: Unmounted lustre-client [ 1898.017822] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 01:10:51 (1776921051) [ 1898.624561] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 1899.287449] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 01:10:52 (1776921052) [ 1967.560399] Lustre: Mounted lustre-client [ 1971.029379] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2443.471776] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 01:19:56 (1776921596) [ 2444.961782] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2444.966180] Lustre: Skipped 3 previous similar messages [ 2455.304327] Lustre: Unmounted lustre-client [ 2541.498259] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2543.466725] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2554.072771] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2554.372316] Lustre: Mounted lustre-client [ 3483.938713] Lustre: 93413:0:(llog_cat.c:971:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9b7d49888800: catlog [0xa:0x5:0x0] crosses index zero [ 3499.174255] LustreError: 101062:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d49888800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3499.179289] LustreError: 101062:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 3499.205256] Lustre: Unmounted lustre-client [ 3517.035744] Key type lgssc unregistered [ 3517.163107] LNet: 101466:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3517.166749] LNetError: 101466:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3517.176653] LNet: Removed LNI 192.168.203.13@tcp [ 3517.471192] Key type .llcrypt unregistered [ 3517.472812] Key type ._llcrypt unregistered [ 3521.232964] Key type ._llcrypt registered [ 3521.235824] Key type .llcrypt registered [ 3521.815901] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 01:37:55 (1776922675) [ 3528.048422] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3528.054696] alg: No test for adler32 (adler32-zlib) [ 3528.928509] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 3529.013627] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 3530.615118] Key type lgssc registered [ 3531.036445] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3560.473135] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3561.856238] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3570.160864] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3571.361616] Lustre: Mounted lustre-client [ 3576.238730] LustreError: 105847:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d45d0c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3576.269185] Lustre: Unmounted lustre-client [ 3627.501410] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3628.711241] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3635.981672] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3655.345126] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 01:40:08 (1776922808) [ 3655.894623] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 3656.534300] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 01:40:10 (1776922810) [ 3666.547809] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3667.838343] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3673.992857] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3689.454595] Lustre: Mounted lustre-client [ 3694.563158] Lustre: lustre-MDT0000-mdc-ffff9b7d45c8b000: Connection to lustre-MDT0000 (at 192.168.203.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3699.683833] Lustre: lustre-MDT0001-mdc-ffff9b7d45c8b000: Connection to lustre-MDT0001 (at 192.168.203.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3715.039124] Lustre: 102224:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776922853/real 1776922853] req@0000000017bd787f x1863238479325696/t0(0) o400->MGC192.168.203.113@tcp@192.168.203.113@tcp:26/25 lens 224/224 e 0 to 1 dl 1776922869 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3715.049365] LustreError: MGC192.168.203.113@tcp: Connection to MGS (at 192.168.203.113@tcp) was lost; in progress operations using this service will fail [ 3820.519342] LustreError: lustre-MDT0000-mdc-ffff9b7d45c8b000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 3820.532364] Lustre: lustre-MDT0000-mdc-ffff9b7d45c8b000: Connection restored to 192.168.203.113@tcp (at 192.168.203.113@tcp) [ 3822.631498] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3823.276547] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3824.603458] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3825.634331] LustreError: lustre-MDT0001-mdc-ffff9b7d45c8b000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 3825.645273] Lustre: lustre-MDT0001-mdc-ffff9b7d45c8b000: Connection restored to 192.168.203.113@tcp (at 192.168.203.113@tcp) [ 3826.226699] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 1 sec [ 3831.237779] LustreError: 112769:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d45c8b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3831.280080] Lustre: Unmounted lustre-client [ 3887.537609] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3888.732795] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3896.147252] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3920.740267] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 01:44:34 (1776923074) [ 3930.769466] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3932.104386] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3938.693359] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3954.162315] Lustre: Mounted lustre-client [ 3955.622169] LustreError: 118409:0:(lproc_llite.c:615:ll_max_cached_mb_seq_write()) lustre: cannot set max_cached_mb=0 MB more than 3693 MB: rc = -34 [ 3958.237046] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 01:45:11 (1776923111) [ 3958.546715] LustreError: 119012:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d45d0f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3958.575252] Lustre: Unmounted lustre-client [ 3975.900644] Key type lgssc unregistered [ 3976.022916] LNet: 119415:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3976.026499] LNetError: 119415:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3976.036898] LNet: Removed LNI 192.168.203.13@tcp [ 3976.302185] Key type .llcrypt unregistered [ 3976.303566] Key type ._llcrypt unregistered [ 3980.926560] Key type ._llcrypt registered [ 3980.928115] Key type .llcrypt registered [ 3981.139212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3981.145251] alg: No test for adler32 (adler32-zlib) [ 3982.019955] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 3982.116851] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 3983.711153] Key type lgssc registered [ 3984.152989] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4031.889440] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4033.268854] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4039.615022] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4086.911536] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4088.206952] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4095.563422] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4116.809972] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 01:47:50 (1776923270) [ 4120.833487] Key type lgssc unregistered [ 4120.951579] LNet: 126419:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4120.954637] LNetError: 126419:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4120.960553] LNet: Removed LNI 192.168.203.13@tcp [ 4121.234584] Key type .llcrypt unregistered [ 4121.235933] Key type ._llcrypt unregistered [ 4125.217397] Key type ._llcrypt registered [ 4125.218880] Key type .llcrypt registered [ 4125.427543] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4125.433586] alg: No test for adler32 (adler32-zlib) [ 4126.314570] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 4126.418276] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 4128.015949] Key type lgssc registered [ 4128.467216] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4146.387116] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4147.798212] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4154.309183] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4200.902591] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4202.140374] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4209.655114] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4231.070907] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 01:49:44 (1776923384) [ 4258.148228] Lustre: Mounted lustre-client [ 4260.534385] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4271.860779] Lustre: DEBUG MARKER: ADD OST3 [ 4278.498388] Lustre: DEBUG MARKER: STOP OST3 [ 4283.370175] Lustre: DEBUG MARKER: START OST3 again [ 4284.383577] Lustre: lustre-OST0000-osc-ffff9b7d46ce5800: disconnect after 25s idle [ 4284.388901] Lustre: lustre-OST0003-osc-ffff9b7d46ce5800: Connection to lustre-OST0003 (at 192.168.203.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4287.546842] Lustre: lustre-OST0003-osc-ffff9b7d46ce5800: Connection restored to 192.168.203.113@tcp (at 192.168.203.113@tcp) [ 4301.880992] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4301.886678] LustreError: 136957:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9b7d46ce5800: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 4312.193963] Lustre: Unmounted lustre-client [ 4357.514680] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4358.729465] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4366.093945] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4385.420312] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 01:52:19 (1776923539) [ 4415.199395] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4416.395284] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4423.787134] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4460.935713] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4462.214492] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4468.158868] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4468.821960] LNetError: 126843:0:(lib-move.c:2323:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 4479.473077] Lustre: Mounted lustre-client [ 4484.024434] LustreError: 145236:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d59e7b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4484.050524] Lustre: Unmounted lustre-client [ 4501.123892] Key type lgssc unregistered [ 4523.743214] LNet: 1 local NIs in recovery (showing 1): 192.168.203.13@tcp [ 4530.911442] LNet: 145644:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4530.915291] LNetError: 145644:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4601.574143] LNet: Removed LNI 192.168.203.13@tcp [ 4601.833112] Key type .llcrypt unregistered [ 4601.834299] Key type ._llcrypt unregistered [ 4607.173539] Key type ._llcrypt registered [ 4607.174414] Key type .llcrypt registered [ 4607.642698] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 01:56:01 (1776923761) [ 4613.213450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4613.232931] alg: No test for adler32 (adler32-zlib) [ 4614.111623] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 4614.197316] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 4615.783139] Key type lgssc registered [ 4616.174813] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4644.124559] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4645.261042] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4652.286261] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4707.879914] Lustre: Mounted lustre-client [ 4710.156867] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4719.761785] LustreError: 152418:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d4953b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4719.785657] Lustre: Unmounted lustre-client [ 4719.812600] LNetError: 146824:0:(lib-socket.c:314:lnet_sock_create()) Error trying to bind to fe80::54fc:74ff:fe51:fc74/1023: rc = -22 [ 4719.816746] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 4719.869693] Lustre: Mounted lustre-client [ 4720.328713] LustreError: 152568:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d45c8e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4720.332475] LustreError: 152568:0:(lov_obd.c:786:lov_cleanup()) Skipped 7 previous similar messages [ 4720.365965] Lustre: Unmounted lustre-client [ 4720.367163] Lustre: Skipped 3 previous similar messages [ 4720.967306] Lustre: Mounted lustre-client [ 4720.968784] Lustre: Skipped 6 previous similar messages [ 4721.062575] Lustre: 152726:0:(obd_mount.c:166: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 [ 4721.068228] LNetError: 146825:0:(lib-socket.c:314:lnet_sock_create()) Error trying to bind to fe80::54fc:74ff:fe51:fc74/1023: rc = -22 [ 4721.071742] LNetError: 146825:0:(lib-socket.c:314:lnet_sock_create()) Skipped 1 previous similar message [ 4721.074427] LNetError: Unexpected error -22 connecting to 2001:db8:85a3::8a2e:370:7334@tcp at host [2001:db8:85a3::8a2e:370:7334]:988 [ 4721.078857] LNetError: Skipped 1 previous similar message [ 4723.668758] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 01:57:57 (1776923877) [ 4773.983104] LNet: 1 local NIs in recovery (showing 1): 192.168.203.13@tcp [ 4780.369956] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4781.493118] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4788.386208] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4825.264217] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4826.464858] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4832.163065] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4837.407226] LustreError: 158166:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 4882.527142] LNet: 1 local NIs in recovery (showing 1): 192.168.203.13@tcp [ 4944.991164] LNet: 1 local NIs in recovery (showing 1): 192.168.203.13@tcp [ 4976.607341] 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 [ 4976.614897] Lustre: Unmounted lustre-client [ 4976.616147] Lustre: Skipped 6 previous similar messages [ 4976.622264] LustreError: 158166:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 5002.773428] Key type lgssc unregistered [ 5014.623151] LNet: 1 local NIs in recovery (showing 1): 192.168.203.13@tcp [ 5024.415396] LNet: 158632:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5024.418151] LNetError: 158632:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5093.029705] LNet: Removed LNI 192.168.203.13@tcp [ 5093.298117] Key type .llcrypt unregistered [ 5093.299489] Key type ._llcrypt unregistered [ 5098.696270] Key type ._llcrypt registered [ 5098.697078] Key type .llcrypt registered [ 5099.210372] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 02:04:12 (1776924252) [ 5105.220515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5105.250106] alg: No test for adler32 (adler32-zlib) [ 5106.118107] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 5106.212499] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 5107.799184] Key type lgssc registered [ 5108.207851] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5153.785523] Lustre: Mounted lustre-client [ 5156.174993] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5166.910955] LustreError: 164104:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d4a7c9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5166.979356] Lustre: Unmounted lustre-client [ 5236.389527] Lustre: Mounted lustre-client [ 5238.603554] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5245.192456] LustreError: 167125:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d60a0c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5245.196873] LustreError: 167125:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5245.228252] Lustre: Unmounted lustre-client [ 5281.042329] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 02:07:14 (1776924434) [ 5311.023894] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5312.129504] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5319.293165] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5372.405134] Lustre: Mounted lustre-client [ 5374.668242] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5383.300403] LustreError: 173867:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d5084c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5383.307274] LustreError: 173867:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5383.342509] Lustre: Unmounted lustre-client [ 5438.316956] Lustre: Mounted lustre-client [ 5440.643340] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5451.586095] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 02:10:05 (1776924605) [ 5451.858920] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5462.138160] Lustre: Unmounted lustre-client [ 5534.436964] Lustre: Mounted lustre-client [ 5536.780598] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5543.898683] LustreError: 181113:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d59e78800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5543.901954] LustreError: 181113:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5543.930139] Lustre: Unmounted lustre-client [ 5544.082419] Lustre: Mounted lustre-client [ 5546.903403] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 02:11:40 (1776924700) [ 5608.333122] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5609.442934] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5616.412335] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5632.568580] Key type lgssc unregistered [ 5632.682455] LNet: 186099:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5632.684466] LNetError: 186099:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5632.694733] LNet: Removed LNI 192.168.203.13@tcp [ 5632.932109] Key type .llcrypt unregistered [ 5632.933491] Key type ._llcrypt unregistered [ 5752.776526] Key type ._llcrypt registered [ 5752.778302] Key type .llcrypt registered [ 5753.002676] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5753.018127] alg: No test for adler32 (adler32-zlib) [ 5753.907302] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 5754.004309] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 5755.591160] Key type lgssc registered [ 5756.012119] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5771.536193] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 02:15:25 (1776924925) [ 5784.116690] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5785.225434] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5792.544597] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5793.723788] Lustre: Mounted lustre-client [ 5795.073585] LustreError: 190036:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d4434f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5795.107202] Lustre: Unmounted lustre-client [ 5795.188755] Lustre: Mounted lustre-client [ 5795.704873] LustreError: 190169:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d45d0d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5795.709068] LustreError: 190169:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5795.735268] Lustre: Unmounted lustre-client [ 5795.736471] Lustre: Skipped 1 previous similar message [ 5804.934215] Key type lgssc unregistered [ 5805.058531] LNet: 190571:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5805.061850] LNetError: 190571:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5805.071420] LNet: Removed LNI 192.168.203.13@tcp [ 5805.299767] Key type .llcrypt unregistered [ 5805.301127] Key type ._llcrypt unregistered [ 5810.660421] Key type ._llcrypt registered [ 5810.661689] Key type .llcrypt registered [ 5811.146755] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 02:16:04 (1776924964) [ 5812.244507] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5812.286125] alg: No test for adler32 (adler32-zlib) [ 5813.141263] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 5813.230563] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 5814.815225] Key type lgssc registered [ 5815.242907] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5831.946439] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5833.057974] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5838.472663] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5854.190635] Lustre: Mounted lustre-client [ 5856.812418] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5857.353164] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5859.299866] Lustre: lustre-MDT0000-mdc-ffff9b7d50e9c800: Connection to lustre-MDT0000 (at 192.168.203.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5864.417406] Lustre: lustre-MDT0001-mdc-ffff9b7d50e9c800: Connection to lustre-MDT0001 (at 192.168.203.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5869.537169] Lustre: lustre-OST0000-osc-ffff9b7d50e9c800: Connection to lustre-OST0000 (at 192.168.203.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5879.776609] LustreError: MGC192.168.203.113@tcp: Connection to MGS (at 192.168.203.113@tcp) was lost; in progress operations using this service will fail [ 5884.897639] Lustre: Evicted from MGS (at 192.168.203.113@tcp) after server handle changed from 0x5eede9b18c9c41c3 to 0x5eede9b18c9c4375 [ 5884.902568] Lustre: MGC192.168.203.113@tcp: Connection restored to 192.168.203.113@tcp (at 192.168.203.113@tcp) [ 5894.113585] LustreError: lustre-MDT0000-mdc-ffff9b7d50e9c800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5894.119115] Lustre: lustre-MDT0000-mdc-ffff9b7d50e9c800: Connection restored to 192.168.203.113@tcp (at 192.168.203.113@tcp) [ 5894.868824] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5901.520850] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 6 sec [ 5902.642788] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5903.175535] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5903.626097] LustreError: 196013:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d50e9c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5903.653948] Lustre: Unmounted lustre-client [ 5920.522566] Key type lgssc unregistered [ 5920.635525] LNet: 196415:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5920.638762] LNetError: 196415:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5920.646365] LNet: Removed LNI 192.168.203.13@tcp [ 5920.866451] Key type .llcrypt unregistered [ 5920.867421] Key type ._llcrypt unregistered [ 5929.594720] Key type ._llcrypt registered [ 5929.595734] Key type .llcrypt registered [ 5929.780890] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5929.785796] alg: No test for adler32 (adler32-zlib) [ 5930.644086] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 5930.729382] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 5932.311122] Key type lgssc registered [ 5932.704322] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5960.718321] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5961.715171] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5968.867584] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5990.989188] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 02:19:04 (1776925144) [ 6017.915231] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6018.973899] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6025.852394] Lustre: DEBUG MARKER: oleg313-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6027.014372] Lustre: Mounted lustre-client [ 6029.043735] Lustre: Mounted lustre-client [ 6030.173752] LustreError: 204431:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d47243000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6030.199169] Lustre: Unmounted lustre-client [ 6030.736399] LustreError: 204471:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d4a7c9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6030.739495] LustreError: 204471:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 6030.758302] Lustre: Unmounted lustre-client [ 6030.759407] Lustre: Skipped 1 previous similar message [ 6033.869700] LustreError: 204995:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9b7d49159800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6033.873148] LustreError: 204995:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6033.894113] Lustre: Unmounted lustre-client [ 6033.895149] Lustre: Skipped 2 previous similar messages [ 6050.648743] Key type lgssc unregistered [ 6050.759805] LNet: 205396:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6050.761828] LNetError: 205396:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6050.774380] LNet: Removed LNI 192.168.203.13@tcp [ 6050.982171] Key type .llcrypt unregistered [ 6050.983668] Key type ._llcrypt unregistered [ 6054.200656] Key type ._llcrypt registered [ 6054.202204] Key type .llcrypt registered [ 6054.684607] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 02:20:08 (1776925208) [ 6058.344251] Key type .llcrypt unregistered [ 6058.345457] Key type ._llcrypt unregistered [ 6058.611136] Key type ._llcrypt registered [ 6058.612094] Key type .llcrypt registered [ 6058.778341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [ 6058.783017] alg: No test for adler32 (adler32-zlib) [ 6059.634496] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6059.722398] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6061.311133] Key type lgssc registered [ 6061.671133] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6068.954487] Key type lgssc unregistered [ 6069.071582] LNet: 207545:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6069.074696] LNetError: 207545:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6069.085309] LNet: Removed LNI 192.168.203.13@tcp [ 6069.304457] Key type .llcrypt unregistered [ 6069.305364] Key type ._llcrypt unregistered [ 6069.421218] Key type ._llcrypt registered [ 6069.422531] Key type .llcrypt registered [ 6069.899697] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 02:20:23 (1776925223) [ 6073.478359] Key type .llcrypt unregistered [ 6073.479529] Key type ._llcrypt unregistered [ 6073.806260] Key type ._llcrypt registered [ 6073.807461] Key type .llcrypt registered [ 6073.957265] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6073.961648] alg: No test for adler32 (adler32-zlib) [ 6074.824640] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6074.900640] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6076.487223] Key type lgssc registered [ 6076.864117] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6081.830301] Key type lgssc unregistered [ 6081.955419] LNet: 209094:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6081.958320] LNetError: 209094:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6081.969365] LNet: Removed LNI 192.168.203.13@tcp [ 6082.194351] Key type .llcrypt unregistered [ 6082.195409] Key type ._llcrypt unregistered [ 6084.382148] Key type ._llcrypt registered [ 6084.383610] Key type .llcrypt registered [ 6084.621404] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6084.630085] alg: No test for adler32 (adler32-zlib) [ 6085.512170] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6085.596770] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6087.183138] Key type lgssc registered [ 6087.568836] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6094.914897] Key type lgssc unregistered [ 6095.030342] LNet: 210859:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6095.033088] LNetError: 210859:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6095.041319] LNet: Removed LNI 192.168.203.13@tcp [ 6095.254448] Key type .llcrypt unregistered [ 6095.255455] Key type ._llcrypt unregistered [ 6095.367447] Key type ._llcrypt registered [ 6095.368311] Key type .llcrypt registered [ 6095.795459] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 02:20:49 (1776925249) [ 6099.286296] Key type .llcrypt unregistered [ 6099.287185] Key type ._llcrypt unregistered [ 6099.531077] Key type ._llcrypt registered [ 6099.531957] Key type .llcrypt registered [ 6099.706228] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6099.713220] alg: No test for adler32 (adler32-zlib) [ 6100.567876] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6100.645907] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6102.223114] Key type lgssc registered [ 6102.609218] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6107.443288] Key type lgssc unregistered [ 6107.548429] LNet: 212403:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6107.551482] LNetError: 212403:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6107.563392] LNet: Removed LNI 192.168.203.13@tcp [ 6107.776446] Key type .llcrypt unregistered [ 6107.777744] Key type ._llcrypt unregistered [ 6109.837310] Key type ._llcrypt registered [ 6109.839016] Key type .llcrypt registered [ 6110.073278] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6110.078381] alg: No test for adler32 (adler32-zlib) [ 6110.935772] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6111.026833] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6112.615139] Key type lgssc registered [ 6113.016046] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6117.998304] Key type lgssc unregistered [ 6118.110351] LNet: 213706:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6118.112947] LNetError: 213706:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6118.121286] LNet: Removed LNI 192.168.203.13@tcp [ 6118.338372] Key type .llcrypt unregistered [ 6118.339432] Key type ._llcrypt unregistered [ 6120.506806] Key type ._llcrypt registered [ 6120.507769] Key type .llcrypt registered [ 6120.693583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6120.705628] alg: No test for adler32 (adler32-zlib) [ 6121.554505] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6121.629567] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6123.223093] Key type lgssc registered [ 6123.586882] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6128.344705] Key type lgssc unregistered [ 6128.453337] LNet: 215005:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6128.456089] LNetError: 215005:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6128.462272] LNet: Removed LNI 192.168.203.13@tcp [ 6128.687376] Key type .llcrypt unregistered [ 6128.689015] Key type ._llcrypt unregistered [ 6130.821978] Key type ._llcrypt registered [ 6130.824963] Key type .llcrypt registered [ 6130.893062] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6130.897389] alg: No test for adler32 (adler32-zlib) [ 6131.755081] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6131.834089] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6133.415129] Key type lgssc registered [ 6133.766732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6141.229765] Key type lgssc unregistered [ 6141.343423] LNet: 216769:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6141.345941] LNetError: 216769:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6141.354415] LNet: Removed LNI 192.168.203.13@tcp [ 6141.567383] Key type .llcrypt unregistered [ 6141.568776] Key type ._llcrypt unregistered [ 6141.684849] Key type ._llcrypt registered [ 6141.685909] Key type .llcrypt registered [ 6142.149108] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 02:21:35 (1776925295) [ 6145.938273] Key type .llcrypt unregistered [ 6145.939234] Key type ._llcrypt unregistered [ 6146.216615] Key type ._llcrypt registered [ 6146.217906] Key type .llcrypt registered [ 6146.389370] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6146.393964] alg: No test for adler32 (adler32-zlib) [ 6147.266162] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6147.356301] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6148.951118] Key type lgssc registered [ 6149.324797] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6154.331060] Key type lgssc unregistered [ 6154.460382] LNet: 218316:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6154.462479] LNetError: 218316:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6154.475125] LNet: Removed LNI 192.168.203.13@tcp [ 6154.712437] Key type .llcrypt unregistered [ 6154.713415] Key type ._llcrypt unregistered [ 6157.012292] Key type ._llcrypt registered [ 6157.013288] Key type .llcrypt registered [ 6157.195348] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6157.200322] alg: No test for adler32 (adler32-zlib) [ 6158.062823] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6158.138726] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6159.727165] Key type lgssc registered [ 6160.126088] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6165.024607] Key type lgssc unregistered [ 6165.150362] LNet: 219627:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6165.152481] LNetError: 219627:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6165.158423] LNet: Removed LNI 192.168.203.13@tcp [ 6165.382685] Key type .llcrypt unregistered [ 6165.383793] Key type ._llcrypt unregistered [ 6167.431499] Key type ._llcrypt registered [ 6167.433082] Key type .llcrypt registered [ 6167.584060] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6167.588343] alg: No test for adler32 (adler32-zlib) [ 6168.472090] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6168.551411] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6170.135088] Key type lgssc registered [ 6170.489399] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6175.429670] Key type lgssc unregistered [ 6175.545343] LNet: 220929:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6175.547556] LNetError: 220929:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6175.555338] LNet: Removed LNI 192.168.203.13@tcp [ 6175.757380] Key type .llcrypt unregistered [ 6175.758386] Key type ._llcrypt unregistered [ 6177.850949] Key type ._llcrypt registered [ 6177.852142] Key type .llcrypt registered [ 6178.073586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6178.078593] alg: No test for adler32 (adler32-zlib) [ 6178.931889] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6179.014023] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6180.591089] Key type lgssc registered [ 6180.973845] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6185.748057] Key type lgssc unregistered [ 6185.863271] LNet: 222234:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6185.865187] LNetError: 222234:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6185.872294] LNet: Removed LNI 192.168.203.13@tcp [ 6186.072364] Key type .llcrypt unregistered [ 6186.073575] Key type ._llcrypt unregistered [ 6188.189784] Key type ._llcrypt registered [ 6188.194443] Key type .llcrypt registered [ 6188.436989] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6188.441865] alg: No test for adler32 (adler32-zlib) [ 6189.290526] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6189.378234] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6190.967159] Key type lgssc registered [ 6191.365327] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6196.063776] Key type lgssc unregistered [ 6196.185326] LNet: 223557:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6196.188325] LNetError: 223557:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6196.196364] LNet: Removed LNI 192.168.203.13@tcp [ 6196.403580] Key type .llcrypt unregistered [ 6196.404613] Key type ._llcrypt unregistered [ 6198.385028] Key type ._llcrypt registered [ 6198.385875] Key type .llcrypt registered [ 6198.548152] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6198.552632] alg: No test for adler32 (adler32-zlib) [ 6199.396138] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6199.474179] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6201.055144] Key type lgssc registered [ 6201.466626] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6206.157471] Key type lgssc unregistered [ 6206.268265] LNet: 224872:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6206.270437] LNetError: 224872:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6206.280304] LNet: Removed LNI 192.168.203.13@tcp [ 6206.477404] Key type .llcrypt unregistered [ 6206.478340] Key type ._llcrypt unregistered [ 6214.043631] Key type ._llcrypt registered [ 6214.044856] Key type .llcrypt registered [ 6214.561147] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 02:22:48 (1776925368) [ 6218.553285] Key type .llcrypt unregistered [ 6218.554492] Key type ._llcrypt unregistered [ 6218.977310] Key type ._llcrypt registered [ 6218.979267] Key type .llcrypt registered [ 6219.213903] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6219.219904] alg: No test for adler32 (adler32-zlib) [ 6220.098925] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6220.197417] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6221.791196] Key type lgssc registered [ 6222.242325] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6227.831624] Key type lgssc unregistered [ 6227.949446] LNet: 227185:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6227.951458] LNetError: 227185:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6227.959342] LNet: Removed LNI 192.168.203.13@tcp [ 6228.186491] Key type .llcrypt unregistered [ 6228.187491] Key type ._llcrypt unregistered [ 6230.475228] Key type ._llcrypt registered [ 6230.476911] Key type .llcrypt registered [ 6230.680555] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6230.686522] alg: No test for adler32 (adler32-zlib) [ 6231.551331] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6231.642780] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6233.231177] Key type lgssc registered [ 6233.650729] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6239.051983] Key type lgssc unregistered [ 6239.178338] LNet: 228500:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6239.180858] LNetError: 228500:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6239.191287] LNet: Removed LNI 192.168.203.13@tcp [ 6239.426405] Key type .llcrypt unregistered [ 6239.427552] Key type ._llcrypt unregistered [ 6241.720764] Key type ._llcrypt registered [ 6241.722410] Key type .llcrypt registered [ 6241.921032] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6241.925916] alg: No test for adler32 (adler32-zlib) [ 6242.786326] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6242.883568] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6244.471162] Key type lgssc registered [ 6244.885803] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6250.376771] Key type lgssc unregistered [ 6250.490639] LNet: 229806:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6250.492905] LNetError: 229806:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6250.504333] LNet: Removed LNI 192.168.203.13@tcp [ 6250.736570] Key type .llcrypt unregistered [ 6250.738599] Key type ._llcrypt unregistered [ 6253.017536] Key type ._llcrypt registered [ 6253.019658] Key type .llcrypt registered [ 6253.185860] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6253.190692] alg: No test for adler32 (adler32-zlib) [ 6254.040347] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6254.115408] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6255.695113] Key type lgssc registered [ 6256.047057] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6263.296571] Key type lgssc unregistered [ 6263.405336] LNet: 231593:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6263.407598] LNetError: 231593:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6263.417312] LNet: Removed LNI 192.168.203.13@tcp [ 6263.619321] Key type .llcrypt unregistered [ 6263.620358] Key type ._llcrypt unregistered [ 6263.730280] Key type ._llcrypt registered [ 6263.731105] Key type .llcrypt registered [ 6264.146925] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 02:23:37 (1776925417) [ 6269.368397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6269.377652] alg: No test for adler32 (adler32-zlib) [ 6270.236858] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6270.315227] LNet: Added LNI 192.168.203.13@tcp [8/256/0/180] [ 6271.895148] Key type lgssc registered [ 6272.285468] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6370.861100] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 02:25:24 (1776925524) [ 6371.334545] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [ 6388.767735] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 6178 sec ========== 02:25:42 (1776925542) [ 6389.308904] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 02:25:42 (1776925542) === [ 6390.326247] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 02:25:43 (1776925543) === [ 6401.085100] Key type lgssc unregistered [ 6401.200462] LNet: 240613:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6401.203585] LNetError: 240613:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6401.215487] LNet: Removed LNI 192.168.203.13@tcp [ 6401.450577] Key type .llcrypt unregistered [ 6401.452095] Key type ._llcrypt unregistered