[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 505542949 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001009] APIC: Switch to symmetric I/O mode setup [ 0.002245] x2apic enabled [ 0.003005] Switched APIC routing to physical x2apic. [ 0.004015] kvm-guest: setup PV IPIs [ 0.007304] ..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.008021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009012] pid_max: default: 32768 minimum: 301 [ 0.010131] LSM: Security Framework initializing [ 0.011054] Yama: becoming mindful. [ 0.012037] SELinux: Initializing. [ 0.013086] *** VALIDATE selinux *** [ 0.021549] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026097] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028132] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029104] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030110] *** VALIDATE tmpfs *** [ 0.032301] *** VALIDATE proc *** [ 0.033221] *** VALIDATE cgroup *** [ 0.034010] *** VALIDATE cgroup2 *** [ 0.035271] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036147] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038028] Spectre V2 : User space: Vulnerable [ 0.039007] Speculative Store Bypass: Vulnerable [ 0.041603] debug: unmapping init [mem 0xffffffffb5859000-0xffffffffb5860fff] [ 0.043185] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044658] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045025] ... version: 2 [ 0.046012] ... bit width: 48 [ 0.047012] ... generic registers: 4 [ 0.048013] ... value mask: 0000ffffffffffff [ 0.049016] ... max period: 00007fffffffffff [ 0.050017] ... fixed-purpose events: 3 [ 0.051013] ... event mask: 000000070000000f [ 0.053237] rcu: Hierarchical SRCU implementation. [ 0.055409] smp: Bringing up secondary CPUs ... [ 0.056561] x86: Booting SMP configuration: [ 0.057025] .... node #0, CPUs: #1 #2 #3 [ 0.060437] smp: Brought up 1 node, 4 CPUs [ 0.062016] smpboot: Max logical packages: 1 [ 0.063019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.243435] node 0 deferred pages initialised in 176ms [ 0.247108] devtmpfs: initialized [ 0.248318] x86/mm: Memory block size: 128MB [ 0.252086] gcov: version magic: 0x41383552 [ 0.255332] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.259171] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.262518] pinctrl core: initialized pinctrl subsystem [ 0.264480] [ 0.265011] ************************************************************* [ 0.267014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.270019] ** ** [ 0.272015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.275015] ** ** [ 0.277016] ** This means that this kernel is built to expose internal ** [ 0.280020] ** IOMMU data structures, which may compromise security on ** [ 0.282080] ** your system. ** [ 0.284019] ** ** [ 0.286082] ** If you see this message and you are not debugging the ** [ 0.289018] ** kernel, report this immediately to your vendor! ** [ 0.291018] ** ** [ 0.294021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.296017] ************************************************************* [ 0.299924] NET: Registered protocol family 16 [ 0.302522] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.304074] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.307086] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.311050] cpuidle: using governor menu [ 0.312588] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.314637] PCI: Using configuration type 1 for base access [ 0.318160] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.327151] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.328040] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.329158] cryptd: max_cpu_qlen set to 1000 [ 0.332091] ACPI: Added _OSI(Module Device) [ 0.333027] ACPI: Added _OSI(Processor Device) [ 0.334000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.336019] ACPI: Added _OSI(Processor Aggregator Device) [ 0.340834] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.346588] ACPI: Interpreter enabled [ 0.350103] ACPI: PM: (supports S0 S3 S4 S5) [ 0.351015] ACPI: Using IOAPIC for interrupt routing [ 0.353168] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.356436] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.365647] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.367047] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.369017] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.372104] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.375494] acpiphp: Slot [2] registered [ 0.377173] acpiphp: Slot [5] registered [ 0.378161] acpiphp: Slot [6] registered [ 0.380142] acpiphp: Slot [3] registered [ 0.382153] acpiphp: Slot [4] registered [ 0.383115] acpiphp: Slot [7] registered [ 0.385112] acpiphp: Slot [8] registered [ 0.386104] acpiphp: Slot [9] registered [ 0.387130] acpiphp: Slot [10] registered [ 0.389122] acpiphp: Slot [11] registered [ 0.390125] acpiphp: Slot [12] registered [ 0.392201] acpiphp: Slot [13] registered [ 0.393111] acpiphp: Slot [14] registered [ 0.395107] acpiphp: Slot [15] registered [ 0.397162] acpiphp: Slot [16] registered [ 0.398110] acpiphp: Slot [17] registered [ 0.399098] acpiphp: Slot [18] registered [ 0.400101] acpiphp: Slot [19] registered [ 0.402116] acpiphp: Slot [20] registered [ 0.403109] acpiphp: Slot [21] registered [ 0.404100] acpiphp: Slot [22] registered [ 0.406153] acpiphp: Slot [23] registered [ 0.407110] acpiphp: Slot [24] registered [ 0.408113] acpiphp: Slot [25] registered [ 0.410107] acpiphp: Slot [26] registered [ 0.411125] acpiphp: Slot [27] registered [ 0.413130] acpiphp: Slot [28] registered [ 0.414256] acpiphp: Slot [29] registered [ 0.416111] acpiphp: Slot [30] registered [ 0.417135] acpiphp: Slot [31] registered [ 0.419076] PCI host bridge to bus 0000:00 [ 0.420024] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.423032] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.425031] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.427035] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.430033] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.432034] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.434237] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.438102] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.440391] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.447023] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.451091] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.455029] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.457020] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.459018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.461472] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.464572] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.466034] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.467756] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.471025] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.479852] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.485022] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.491582] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.536024] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.541020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.552026] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.565799] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.575019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.582120] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.596018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.604961] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.608449] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.610493] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.612359] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.615283] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.624010] iommu: Default domain type: Passthrough [ 0.625603] SCSI subsystem initialized [ 0.626230] ACPI: bus type USB registered [ 0.628155] usbcore: registered new interface driver usbfs [ 0.630112] usbcore: registered new interface driver hub [ 0.632128] usbcore: registered new device driver usb [ 0.633210] pps_core: LinuxPPS API ver. 1 registered [ 0.635017] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.638117] PTP clock support registered [ 0.640142] EDAC MC: Ver: 3.0.0 [ 0.642198] PCI: Using ACPI for IRQ routing [ 0.643842] NetLabel: Initializing [ 0.645018] NetLabel: domain hash size = 128 [ 0.647011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.649089] NetLabel: unlabeled traffic allowed by default [ 0.651291] vgaarb: loaded [ 0.652278] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.655025] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.661000] clocksource: Switched to clocksource kvm-clock [ 0.774195] VFS: Disk quotas dquot_6.6.0 [ 0.775530] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.777638] *** VALIDATE ramfs *** [ 0.778748] *** VALIDATE hugetlbfs *** [ 0.781112] pnp: PnP ACPI init [ 0.783399] pnp: PnP ACPI: found 6 devices [ 0.801251] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.804595] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.806660] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.808535] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.810739] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.813529] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.816434] NET: Registered protocol family 2 [ 0.818732] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.824163] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.827690] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.833103] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.836347] TCP: Hash tables configured (established 65536 bind 65536) [ 0.839560] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.842275] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.844828] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.847358] NET: Registered protocol family 1 [ 0.850176] RPC: Registered named UNIX socket transport module. [ 0.852238] RPC: Registered udp transport module. [ 0.853690] RPC: Registered tcp transport module. [ 0.855223] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.857220] NET: Registered protocol family 44 [ 0.858578] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.860467] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.862264] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.864153] PCI: CLS 0 bytes, default 64 [ 0.865722] Unpacking initramfs... [ 2.307367] debug: unmapping init [mem 0xffff9c58fcc64000-0xffff9c58fffcffff] [ 2.311402] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.313570] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.316419] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.853677] Initialise system trusted keyrings [ 2.854793] Key type blacklist registered [ 2.856537] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.869460] zbud: loaded [ 2.872654] *** VALIDATE nfs *** [ 2.873729] *** VALIDATE nfs4 *** [ 2.875195] pstore: using deflate compression [ 2.878610] Platform Keyring initialized [ 2.979439] NET: Registered protocol family 38 [ 2.981281] Key type asymmetric registered [ 2.982658] Asymmetric key parser 'x509' registered [ 2.984421] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.987392] io scheduler mq-deadline registered [ 2.989168] io scheduler kyber registered [ 2.990657] io scheduler bfq registered [ 2.992550] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.995554] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.998920] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.002356] ACPI: Power Button [PWRF] [ 3.008724] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.017053] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.029161] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.057087] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.086779] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.091689] Non-volatile memory driver v1.3 [ 3.093208] Linux agpgart interface v0.103 [ 3.126690] virtio_blk virtio1: [vda] 145904 512-byte logical blocks (74.7 MB/71.2 MiB) [ 3.130698] vda: detected capacity change from 0 to 74702848 [ 3.146480] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.150624] vdb: detected capacity change from 0 to 1073741824 [ 3.159851] libphy: Fixed MDIO Bus: probed [ 3.168278] usbcore: registered new interface driver usbserial_generic [ 3.170441] usbserial: USB Serial support registered for generic [ 3.172576] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.176629] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.178492] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.180968] mousedev: PS/2 mouse device common for all mice [ 3.183604] rtc_cmos 00:05: RTC can wake from S4 [ 3.185603] rtc_cmos 00:05: registered as rtc0 [ 3.189874] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.190731] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.196675] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.197191] intel_pstate: CPU model not supported [ 3.201668] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.205583] hid: raw HID events driver (C) Jiri Kosina [ 3.208779] usbcore: registered new interface driver usbhid [ 3.210242] usbhid: USB HID core driver [ 3.211387] drop_monitor: Initializing network drop monitor service [ 3.213025] Initializing XFRM netlink socket [ 3.214540] NET: Registered protocol family 10 [ 3.217251] Segment Routing with IPv6 [ 3.218329] NET: Registered protocol family 17 [ 3.220247] mpls_gso: MPLS GSO support [ 3.224741] RAS: Correctable Errors collector initialized. [ 3.226383] AVX version of gcm_enc/dec engaged. [ 3.227890] AES CTR mode by8 optimization enabled [ 3.310143] sched_clock: Marking stable (3310106442, 0)->(4287659302, -977552860) [ 3.314944] registered taskstats version 1 [ 3.317527] Loading compiled-in X.509 certificates [ 3.320065] zswap: loaded using pool lzo/zbud [ 3.345863] Key type big_key registered [ 3.359256] Key type encrypted registered [ 3.361462] ima: No TPM chip found, activating TPM-bypass! [ 3.363849] ima: Allocated hash algorithm: sha1 [ 3.365433] ima: No architecture policies found [ 3.367168] evm: Initialising EVM extended attributes: [ 3.368825] evm: security.selinux [ 3.370120] evm: security.ima [ 3.371177] evm: security.capability [ 3.372316] evm: HMAC attrs: 0x1 [ 3.374687] rtc_cmos 00:05: setting system clock to 2026-08-15 16:36:51 UTC (1786811811) [ 3.381262] debug: unmapping init [mem 0xffffffffb6803000-0xffffffffb69fffff] [ 3.383911] debug: unmapping init [mem 0xffffffffb5582000-0xffffffffb5858fff] [ 3.389154] Write protecting the kernel read-only data: 28672k [ 3.391504] debug: unmapping init [mem 0xffffffffb3c03000-0xffffffffb3dfffff] [ 3.393484] debug: unmapping init [mem 0xffffffffb4514000-0xffffffffb45fffff] [ 3.416894] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.425686] systemd[1]: Detected virtualization kvm. [ 3.428375] systemd[1]: Detected architecture x86-64. [ 3.431463] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.459354] systemd[1]: No hostname configured. [ 3.461314] systemd[1]: Set hostname to . [ 3.463584] random: systemd: uninitialized urandom read (16 bytes read) [ 3.465664] systemd[1]: Initializing machine ID from random generator. [ 3.595503] random: systemd: uninitialized urandom read (16 bytes read) [ 3.597233] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.600720] random: systemd: uninitialized urandom read (16 bytes read) [ 3.602595] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.607416] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... Starting Create Volatile Files and Directories... [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Timers. Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.368146] device-mapper: uevent: version 1.0.3 [ 4.370492] 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. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.060304] virtio_net virtio0 ens2: renamed from eth0 [ 5.100170] scsi host0: ata_piix [ 5.121186] scsi host1: ata_piix [ 5.122751] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.125561] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.488323] dracut-initqueue[593]: RTNETLINK answers: File exists [ 9.887203] random: crng init done [ 9.889749] random: 7 urandom warning(s) missed due to ratelimiting 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. [ 10.869195] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 13.289112] hrtimer: interrupt took 3102458 ns [ 13.663220] printk: systemd: 26 output lines suppressed due to ratelimiting [ 14.544830] SELinux: Disabled at runtime. [ 14.680761] 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) [ 14.702387] systemd[1]: Detected virtualization kvm. [ 14.707267] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 16.392156] systemd[1]: initrd-switch-root.service: Succeeded. [ 16.405837] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 16.420713] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 16.431121] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 16.434888] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 16.453490] systemd[1]: Starting Journal Service... Starting Journal Service... [ 16.493214] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on initctl Compatibility Named Pipe. Starting Remount Root and Kernel File Systems... [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on udev Control Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on Process Core Dump[ 16.735550] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice User and Session Slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. Starting Apply Kernel Variables... [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting POSIX Message Queue File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Reached target Slices. Mounting Kernel Debug File System... [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 17.973597] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 19.059536] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 19.196672] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 19.717201] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 19.814914] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (7s / no limit)[ 24.134866] Key type dns_resolver registered [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (8s / no limit)[ 25.095224] NFS: Registering the id_resolver key type [ 25.098147] Key type id_resolver registered [ 25.100208] Key type id_legacy registered [ *** ] 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 Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ 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 oleg120-client login: [ 99.969270] libcfs: loading out-of-tree module taints kernel. [ 100.175501] Key type ._llcrypt registered [ 100.182203] Key type .llcrypt registered [ 101.008760] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 101.022293] alg: No test for adler32 (adler32-zlib) [ 102.525657] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 103.575180] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 105.338044] Key type lgssc registered [ 107.487132] Lustre: Echo OBD driver; http://www.lustre.org/ [ 297.527488] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 304.920594] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 321.236838] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing check_logdir /tmp/testlogs/ [ 323.042473] Lustre: lustre-OST0000-osc-ffff9c5958c8a800: disconnect after 22s idle [ 327.466366] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing yml_node [ 332.349949] Lustre: DEBUG MARKER: Client: 2.17.57.3 [ 334.702154] Lustre: DEBUG MARKER: MDS: 2.17.57.3 [ 337.968280] Lustre: DEBUG MARKER: OSS: 2.17.57.3 [ 340.450763] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sat Aug 15 12:42:26 EDT 2026 [ 358.900202] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 360.773577] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 363.930397] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 374.291252] Lustre: Unmounted lustre-client [ 485.209627] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 491.407426] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 514.519523] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 554.429292] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 12:46:01 (1786812361) [ 606.110622] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 614.305988] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 650.508514] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 698.152276] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 704.139985] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 747.995429] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 770.006830] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 12:49:36 (1786812576) [ 877.015789] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 882.020175] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 908.826216] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 909.495294] LustreError: 2407:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0000_UUID failed: -22 [ 909.588403] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 929.685246] Lustre: Unmounted lustre-client [ 960.418353] Key type lgssc unregistered [ 960.812976] LNet: 21893:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 960.826775] LNetError: 21893:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 961.897582] LNet: Removed LNI 192.168.201.20@tcp [ 962.835306] Key type .llcrypt unregistered [ 962.897538] Key type ._llcrypt unregistered [ 982.843493] Key type ._llcrypt registered [ 982.846918] Key type .llcrypt registered [ 984.578345] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 12:53:10 (1786812790) [ 986.962078] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 987.001156] alg: No test for adler32 (adler32-zlib) [ 988.073917] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 988.406547] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 990.111215] Key type lgssc registered [ 991.365097] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1043.477555] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1049.033984] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1068.422967] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1071.253376] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 1082.714837] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 12:54:49 (1786812889) [ 1092.020733] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 12:54:58 (1786812898) [ 1101.521342] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 12:55:07 (1786812907) [ 1114.117308] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 12:55:20 (1786812920) [ 1139.516971] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 12:55:46 (1786812946) [ 1154.389491] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 12:56:01 (1786812961) [ 1167.595703] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 12:56:14 (1786812974) [ 1176.121658] Lustre: Unmounted lustre-client [ 1176.579335] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 1178.432541] Lustre: Unmounted lustre-client [ 1202.376755] Key type lgssc unregistered [ 1202.574618] LNet: 30266:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1202.579456] LNetError: 30266:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1202.592560] LNet: Removed LNI 192.168.201.20@tcp [ 1203.191280] Key type .llcrypt unregistered [ 1203.193386] Key type ._llcrypt unregistered [ 1230.893881] Key type ._llcrypt registered [ 1230.896270] Key type .llcrypt registered [ 1231.495251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1231.513889] alg: No test for adler32 (adler32-zlib) [ 1232.710831] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 1233.055091] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 1234.799343] Key type lgssc registered [ 1236.220432] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1318.574935] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1325.227547] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1351.619044] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1404.622401] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 13:00:11 (1786813211) [ 1439.738382] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1446.155894] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1469.129899] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1471.021450] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 1612.353348] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 13:03:39 (1786813419) [ 1659.085216] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 13:04:25 (1786813465) [ 1670.066483] Lustre: Unmounted lustre-client [ 1762.630180] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1769.378462] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1795.569164] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1796.401830] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 1802.652806] Lustre: Unmounted lustre-client [ 1830.401612] Key type lgssc unregistered [ 1830.692600] LNet: 41607:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1830.703385] LNetError: 41607:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1831.718575] LNet: Removed LNI 192.168.201.20@tcp [ 1832.627493] Key type .llcrypt unregistered [ 1832.630805] Key type ._llcrypt unregistered [ 1852.759921] Key type ._llcrypt registered [ 1852.762121] Key type .llcrypt registered [ 1854.071620] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 13:07:40 (1786813660) [ 1857.049879] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1857.061690] alg: No test for adler32 (adler32-zlib) [ 1858.271899] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 1858.546492] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 1860.199601] Key type lgssc registered [ 1861.842879] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1915.600870] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1920.834038] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1941.631223] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1944.481044] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 1957.080413] Lustre: Unmounted lustre-client [ 2031.853631] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2036.766515] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2055.463138] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2056.411056] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 2061.795406] Lustre: Unmounted lustre-client [ 2097.614368] Key type lgssc unregistered [ 2097.860041] LNet: 47145:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2097.864887] LNetError: 47145:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2097.877878] LNet: Removed LNI 192.168.201.20@tcp [ 2098.654805] Key type .llcrypt unregistered [ 2098.656796] Key type ._llcrypt unregistered [ 2112.105640] Key type ._llcrypt registered [ 2112.107121] Key type .llcrypt registered [ 2113.692406] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 13:12:00 (1786813920) [ 2116.027528] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2116.079389] alg: No test for adler32 (adler32-zlib) [ 2117.227138] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 2117.460572] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 2119.159326] Key type lgssc registered [ 2120.362530] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2167.528097] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2172.698708] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2190.526570] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2193.494906] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 2282.536666] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 13:14:49 (1786814089) [ 2283.694410] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2285.076734] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 13:14:52 (1786814092) [ 2286.369346] Lustre: Unmounted lustre-client [ 2309.954494] Key type lgssc unregistered [ 2310.157558] LNet: 52484:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2310.166877] LNetError: 52484:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2310.186170] LNet: Removed LNI 192.168.201.20@tcp [ 2310.797700] Key type .llcrypt unregistered [ 2310.799933] Key type ._llcrypt unregistered [ 2334.643200] Key type ._llcrypt registered [ 2334.649604] Key type .llcrypt registered [ 2334.830206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2334.841888] alg: No test for adler32 (adler32-zlib) [ 2335.848333] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 2336.051653] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 2337.712678] Key type lgssc registered [ 2338.675484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2370.219530] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 13:16:16 (1786814176) [ 2382.538887] Key type lgssc unregistered [ 2382.789758] LNet: 54894:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2382.794336] LNetError: 54894:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2382.816782] LNet: Removed LNI 192.168.201.20@tcp [ 2383.410410] Key type .llcrypt unregistered [ 2383.412486] Key type ._llcrypt unregistered [ 2393.539818] Key type ._llcrypt registered [ 2393.542142] Key type .llcrypt registered [ 2393.899715] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2393.909876] alg: No test for adler32 (adler32-zlib) [ 2394.882351] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 2395.051437] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 2396.720294] Key type lgssc registered [ 2398.242479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2450.226748] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2455.985486] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2482.703521] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2484.737211] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 2530.045848] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 13:18:56 (1786814336) [ 2532.479334] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2534.261145] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 13:19:01 (1786814341) [ 2536.611874] Lustre: Unmounted lustre-client [ 2606.019775] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2611.543535] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2703.513422] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 13:21:49 (1786814509) [ 2800.824562] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 2806.700650] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2821.623910] Lustre: lustre-MDT0001-mdc-ffff9c5942c17000: Connection to lustre-MDT0001 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2826.725468] Lustre: lustre-OST0000-osc-ffff9c5942c17000: disconnect after 23s idle [ 2838.094534] LustreError: 63583:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.201.120@tcp: cfg command failed: rc = -17 [ 2838.111075] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2838.111075] [ 2838.128164] LustreError: 62850:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2854.034903] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 13:24:20 (1786814660) [ 2916.891081] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3004.240256] Lustre: Unmounted lustre-client [ 3184.251995] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 3191.036882] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3248.205706] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 13:30:54 (1786815054) [ 3249.346070] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3250.108772] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3250.127635] Lustre: Skipped 1 previous similar message [ 3272.257052] Lustre: Unmounted lustre-client [ 3414.380731] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 13:33:41 (1786815221) [ 3415.822334] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3418.440573] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 13:33:44 (1786815224) [ 3518.141544] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 3524.622204] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4178.180950] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 13:46:24 (1786815984) [ 4182.266061] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4182.277263] Lustre: Skipped 1 previous similar message [ 4192.617224] Lustre: Unmounted lustre-client [ 4324.575090] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4331.286801] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4356.119529] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4356.764651] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 6028.783378] Lustre: 95284:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9c595f869800: catlog [0xa:0x5:0x0] crosses index zero [ 6054.425954] Lustre: Unmounted lustre-client [ 6090.635187] Key type lgssc unregistered [ 6090.904057] LNet: 103451:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6090.909559] LNetError: 103451:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6090.935317] LNet: Removed LNI 192.168.201.20@tcp [ 6091.782150] Key type .llcrypt unregistered [ 6091.784101] Key type ._llcrypt unregistered [ 6104.246778] Key type ._llcrypt registered [ 6104.248618] Key type .llcrypt registered [ 6105.676413] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 14:18:32 (1786817912) [ 6122.714813] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6122.762336] alg: No test for adler32 (adler32-zlib) [ 6123.976538] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 6124.231959] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 6125.919206] Key type lgssc registered [ 6127.333635] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6218.398344] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6224.795780] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6250.941830] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6252.658949] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 6273.639525] Lustre: Unmounted lustre-client [ 6402.400824] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6408.626679] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6430.242265] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6473.807441] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 14:24:40 (1786818280) [ 6475.327079] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 6477.293758] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 14:24:43 (1786818283) [ 6509.679750] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6514.667796] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6536.521729] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6537.244433] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 6542.351837] Lustre: lustre-MDT0000-mdc-ffff9c5958657000: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6557.682285] Lustre: lustre-MDT0001-mdc-ffff9c5958657000: Connection to lustre-MDT0001 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6568.863184] Lustre: 104211:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786818360/real 1786818360] req@000000007d78ed74 x1873614399942144/t0(0) o400->MGC192.168.201.120@tcp@192.168.201.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1786818376 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6568.914601] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [ 6714.346476] Lustre: Evicted from MGS (at 192.168.201.120@tcp) after server handle changed from 0x6a52a22bf440bcb4 to 0x6a52a22bf440d34a [ 6714.372382] Lustre: MGC192.168.201.120@tcp: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 6721.027088] Lustre: 113610:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.201.120@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 6724.612603] LustreError: lustre-MDT0000-mdc-ffff9c5958657000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6724.669567] Lustre: lustre-MDT0000-mdc-ffff9c5958657000: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 6729.712990] LustreError: lustre-MDT0001-mdc-ffff9c5958657000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6729.752408] Lustre: lustre-MDT0001-mdc-ffff9c5958657000: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 6737.660412] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6739.822381] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6746.982651] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6749.045436] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6763.957761] Lustre: Unmounted lustre-client [ 6901.448620] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6907.982607] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6935.494269] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6988.318992] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 14:33:14 (1786818794) [ 7029.347281] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7036.369317] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7059.411937] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7060.274528] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 7063.371673] LustreError: 120949:0:(lproc_llite.c:618:ll_max_cached_mb_seq_write()) lustre: cannot set max_cached_mb=0 MB more than 3693 MB: rc = -34 [ 7072.332274] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 14:34:37 (1786818877) [ 7075.798389] Lustre: Unmounted lustre-client [ 7108.136741] Key type lgssc unregistered [ 7108.426425] LNet: 121961:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7108.435637] LNetError: 121961:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7108.458705] LNet: Removed LNI 192.168.201.20@tcp [ 7109.615323] Key type .llcrypt unregistered [ 7109.616933] Key type ._llcrypt unregistered [ 7128.187468] Key type ._llcrypt registered [ 7128.189108] Key type .llcrypt registered [ 7128.986983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7129.021570] alg: No test for adler32 (adler32-zlib) [ 7130.244298] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 7130.580575] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 7132.295219] Key type lgssc registered [ 7133.435541] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7229.351326] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7235.462881] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7258.341853] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7385.936788] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7392.832771] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7424.474090] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7478.436826] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 14:41:24 (1786819284) [ 7493.584099] Key type lgssc unregistered [ 7493.940573] LNet: 129186:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7493.946197] LNetError: 129186:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7493.971519] LNet: Removed LNI 192.168.201.20@tcp [ 7494.855983] Key type .llcrypt unregistered [ 7494.858910] Key type ._llcrypt unregistered [ 7508.728808] Key type ._llcrypt registered [ 7508.736352] Key type .llcrypt registered [ 7509.311603] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7509.327541] alg: No test for adler32 (adler32-zlib) [ 7510.532243] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 7510.851663] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 7512.648869] Key type lgssc registered [ 7514.092936] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7573.891162] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7581.266602] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7610.576749] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7739.477740] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7745.555503] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7771.180692] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7816.752808] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 14:47:03 (1786819623) [ 7901.825666] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 7906.975497] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7917.543535] Lustre: DEBUG MARKER: ADD OST3 [ 7927.263652] Lustre: lustre-OST0000-osc-ffff9c596bae1800: disconnect after 23s idle [ 7930.689636] Lustre: DEBUG MARKER: STOP OST3 [ 7944.197183] Lustre: lustre-OST0003-osc-ffff9c596bae1800: Connection to lustre-OST0003 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7953.548829] Lustre: DEBUG MARKER: START OST3 again [ 7963.548648] Lustre: lustre-OST0003-osc-ffff9c596bae1800: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 7994.611975] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7994.626596] LustreError: 140008:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9c596bae1800: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 8005.000918] Lustre: Unmounted lustre-client [ 8114.548897] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8119.745286] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8144.568551] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8181.958265] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 14:53:08 (1786819988) [ 8269.961226] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8275.248918] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8299.065334] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8368.874736] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8374.864683] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8396.410926] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8398.984204] LNetError: 129599:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 8399.299196] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 8405.351126] Lustre: Unmounted lustre-client [ 8428.514415] Key type lgssc unregistered [ 8452.063563] LNet: 1 local NIs in recovery (showing 1): 192.168.201.20@tcp [ 8457.504538] LNet: 148989:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8457.512637] LNetError: 148989:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8529.261358] LNet: Removed LNI 192.168.201.20@tcp [ 8530.069454] Key type .llcrypt unregistered [ 8530.074042] Key type ._llcrypt unregistered [ 8547.397677] Key type ._llcrypt registered [ 8547.401567] Key type .llcrypt registered [ 8548.759670] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 14:59:15 (1786820355) [ 8566.905340] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8566.945478] alg: No test for adler32 (adler32-zlib) [ 8568.323669] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 8568.689141] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 8570.448439] Key type lgssc registered [ 8571.756625] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8657.025365] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8663.776576] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8690.060030] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8826.991588] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 8832.325285] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8843.883182] Lustre: Unmounted lustre-client [ 8843.982437] LNetError: 150172:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54eb:d0ff:fe02:a56a/1023: rc = -22 [ 8843.992059] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8844.248458] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 8844.416918] Lustre: Unmounted lustre-client [ 8845.760309] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 8845.765556] Lustre: Skipped 2 previous similar messages [ 8845.878151] Lustre: Unmounted lustre-client [ 8845.882980] Lustre: Skipped 2 previous similar messages [ 8847.377861] LNetError: 150172:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54eb:d0ff:fe02:a56a/1023: rc = -22 [ 8847.384201] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8847.807459] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 8847.809654] Lustre: Skipped 2 previous similar messages [ 8848.058157] Lustre: Unmounted lustre-client [ 8848.067348] Lustre: Skipped 2 previous similar messages [ 8848.879803] Lustre: 156129:0:(obd_mount.c:175:lustre_add_mgc_failnodes()) MGC6699:7654::1234:1234:d84@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [ 8848.894396] LNetError: 150172:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54eb:d0ff:fe02:a56a/1023: rc = -22 [ 8848.913181] LNetError: Unexpected error -22 connecting to 2001:db8:85a3::8a2e:370:7334@tcp at host [2001:db8:85a3::8a2e:370:7334]:988 [ 8849.442509] Lustre: 156160:0:(obd_mount.c:175:lustre_add_mgc_failnodes()) MGC193.168.0.240@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [ 8858.351270] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 15:04:24 (1786820664) [ 8907.679484] LNet: 1 local NIs in recovery (showing 1): 192.168.201.20@tcp [ 8974.672594] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8979.453558] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9001.652408] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9074.425460] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9080.925586] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9104.085941] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9109.731907] LustreError: 161751:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 9154.463810] LNet: 1 local NIs in recovery (showing 1): 192.168.201.20@tcp [ 9234.335376] LNet: 1 local NIs in recovery (showing 1): 192.168.201.20@tcp [ 9248.737436] 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 [ 9248.758852] Lustre: Unmounted lustre-client [ 9248.766761] Lustre: Skipped 3 previous similar messages [ 9248.772082] LustreError: 161751:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 9274.762482] Key type lgssc unregistered [ 9294.815929] LNet: 1 local NIs in recovery (showing 1): 192.168.201.20@tcp [ 9296.549279] LNet: 162210:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9296.558166] LNetError: 162210:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9365.164873] LNet: Removed LNI 192.168.201.20@tcp [ 9366.009371] Key type .llcrypt unregistered [ 9366.011713] Key type ._llcrypt unregistered [ 9384.495821] Key type ._llcrypt registered [ 9384.497640] Key type .llcrypt registered [ 9386.110955] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 15:13:12 (1786821192) [ 9409.315703] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9409.332684] alg: No test for adler32 (adler32-zlib) [ 9410.449887] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [ 9410.647654] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 9412.335420] Key type lgssc registered [ 9413.930455] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9561.668186] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 9567.615506] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9587.169856] Lustre: lustre-OST0000-osc-ffff9c5944a6d000: disconnect after 23s idle [ 9588.434610] Lustre: Unmounted lustre-client [ 9746.711530] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [ 9751.093797] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9757.108303] Lustre: Unmounted lustre-client [ 9810.877348] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 15:20:17 (1786821617) [ 9907.600890] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9913.274548] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9936.714787] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10074.493950] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [10080.828450] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10094.083097] Lustre: Unmounted lustre-client [10221.724123] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [10226.614271] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10244.780610] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 15:27:31 (1786822051) [10247.029575] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10257.379531] Lustre: Unmounted lustre-client [10436.468810] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [10442.486057] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10452.282195] Lustre: Unmounted lustre-client [10453.006911] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [10461.500981] Lustre: Unmounted lustre-client [10463.511933] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 15:31:09 (1786822269) [10528.323295] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10534.898638] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10560.030510] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10560.600785] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [10657.787666] LustreError: lustre-OST0001-osc-ffff9c5958c8d800: operation ost_connect to node 192.168.201.120@tcp failed: rc = -19 [10662.896491] Lustre: lustre-MDT0000-mdc-ffff9c5958c8d800: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [10678.276632] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [10678.277538] LustreError: lustre-MDT0000-mdc-ffff9c5958c8d800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10678.309315] Lustre: lustre-MDT0000-mdc-ffff9c5958c8d800: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [10678.326387] Lustre: Evicted from MGS (at 192.168.201.120@tcp) after server handle changed from 0xa75504ecf88e1ded to 0xa75504ecf88e2151 [10723.909198] Lustre: Unmounted lustre-client [10753.444879] Key type lgssc unregistered [10753.686869] LNet: 189763:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10753.693783] LNetError: 189763:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10753.718919] LNet: Removed LNI 192.168.201.20@tcp [10754.641338] Key type .llcrypt unregistered [10754.649290] Key type ._llcrypt unregistered [10768.003754] Key type ._llcrypt registered [10768.006721] Key type .llcrypt registered [10769.242773] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 15:36:15 (1786822575) [10771.365584] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10771.397673] alg: No test for adler32 (adler32-zlib) [10772.418893] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [10772.681584] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [10774.383226] Key type lgssc registered [10775.384506] Lustre: Echo OBD driver; http://www.lustre.org/ [10827.027106] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10832.516259] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10853.554724] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10856.627650] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [10954.229548] LustreError: lustre-OST0001-osc-ffff9c5958226000: operation ost_connect to node 192.168.201.120@tcp failed: rc = -19 [11063.953037] Lustre: Unmounted lustre-client [11102.221713] Key type lgssc unregistered [11102.494446] LNet: 195790:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11102.499279] LNetError: 195790:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11102.516617] LNet: Removed LNI 192.168.201.20@tcp [11103.348311] Key type .llcrypt unregistered [11103.350807] Key type ._llcrypt unregistered [11115.708564] Key type ._llcrypt registered [11115.711866] Key type .llcrypt registered [11116.904822] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 15:42:03 (1786822923) [11146.493190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11146.507302] alg: No test for adler32 (adler32-zlib) [11147.662958] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [11147.945167] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [11149.695363] Key type lgssc registered [11151.415402] Lustre: Echo OBD driver; http://www.lustre.org/ [11246.299393] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11252.404692] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11278.458355] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11318.924841] Key type lgssc unregistered [11319.181661] LNet: 200774:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11319.192548] LNetError: 200774:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11319.225774] LNet: Removed LNI 192.168.201.20@tcp [11320.158652] Key type .llcrypt unregistered [11320.163946] Key type ._llcrypt unregistered [11435.250945] Key type ._llcrypt registered [11435.296913] Key type .llcrypt registered [11435.597916] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11435.606535] alg: No test for adler32 (adler32-zlib) [11436.865367] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [11437.133350] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [11438.839617] Key type lgssc registered [11440.675386] Lustre: Echo OBD driver; http://www.lustre.org/ [11490.035637] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 15:48:16 (1786823296) [11530.822976] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11537.431586] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11560.817832] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11563.607091] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [11566.011636] Lustre: Unmounted lustre-client [11566.347860] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [11566.624217] Lustre: Unmounted lustre-client [11574.937776] Lustre: Unmounted lustre-client [11602.429193] Key type lgssc unregistered [11602.781131] LNet: 205751:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11602.793988] LNetError: 205751:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11602.819793] LNet: Removed LNI 192.168.201.20@tcp [11603.619175] Key type .llcrypt unregistered [11603.623276] Key type ._llcrypt unregistered [11613.819871] Key type ._llcrypt registered [11613.824772] Key type .llcrypt registered [11615.428702] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 15:50:21 (1786823421) [11618.690106] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11618.715986] alg: No test for adler32 (adler32-zlib) [11619.970325] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [11620.312788] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [11621.999270] Key type lgssc registered [11623.463844] Lustre: Echo OBD driver; http://www.lustre.org/ [11670.281326] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11676.239491] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11697.599337] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11700.353631] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [11712.258529] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11714.162707] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11721.211208] Lustre: lustre-MDT0000-mdc-ffff9c5948d1b800: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [11731.487429] Lustre: lustre-MDT0001-mdc-ffff9c5948d1b800: Connection to lustre-MDT0001 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [11742.687428] Lustre: 206426:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786823534/real 1786823534] req@00000000b220b596 x1873620162844544/t0(0) o400->MGC192.168.201.120@tcp@192.168.201.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1786823550 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11742.722593] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [11776.501198] Lustre: Evicted from MGS (at 192.168.201.120@tcp) after server handle changed from 0xcf82ee2228f7a7c5 to 0xcf82ee2228f7a969 [11776.524716] Lustre: MGC192.168.201.120@tcp: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [11812.715960] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11814.623531] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [11817.471908] LustreError: lustre-MDT0000-mdc-ffff9c5948d1b800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11817.513318] Lustre: lustre-MDT0000-mdc-ffff9c5948d1b800: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [11820.231109] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11822.049787] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11824.275802] Lustre: Unmounted lustre-client [11851.931748] Key type lgssc unregistered [11852.081671] LNet: 211288:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11852.084293] LNetError: 211288:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11852.092879] LNet: Removed LNI 192.168.201.20@tcp [11852.767249] Key type .llcrypt unregistered [11852.774194] Key type ._llcrypt unregistered [11883.169764] Key type ._llcrypt registered [11883.172175] Key type .llcrypt registered [11883.741968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11883.753909] alg: No test for adler32 (adler32-zlib) [11884.822405] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [11885.015956] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [11886.663195] Key type lgssc registered [11887.946521] Lustre: Echo OBD driver; http://www.lustre.org/ [11969.242475] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11975.910995] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11998.818599] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12037.705913] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 15:57:24 (1786823844) [12136.328624] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12143.808052] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12166.440145] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12169.201117] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [12174.385206] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [12175.536834] LustreError: 211890:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [12175.569500] Lustre: Mounted lustre-client - version 2.17.57_3_g67c4c6e [12175.574363] Lustre: Skipped 1 previous similar message [12179.867770] Lustre: Unmounted lustre-client [12182.317858] Lustre: Unmounted lustre-client [12184.439855] Lustre: Unmounted lustre-client [12186.441273] Lustre: Unmounted lustre-client [12199.456508] Lustre: Unmounted lustre-client [12199.460864] Lustre: Skipped 1 previous similar message [12244.309404] Key type lgssc unregistered [12244.545561] LNet: 220456:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12244.562491] LNetError: 220456:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12244.578832] LNet: Removed LNI 192.168.201.20@tcp [12245.387427] Key type .llcrypt unregistered [12245.392833] Key type ._llcrypt unregistered [12256.339734] Key type ._llcrypt registered [12256.342574] Key type .llcrypt registered [12258.070055] Lustre: DEBUG MARKER: == conf-sanity test 165: mkfs.lustre and tunefs.lustre handle autodegrade properly ========================================================== 16:01:04 (1786824064) [12259.675474] Lustre: DEBUG MARKER: SKIP: conf-sanity test_165 zfs only test [12261.703754] Lustre: DEBUG MARKER: == conf-sanity test 166: tunefs.lustre records disk changes to syslog ========================================================== 16:01:08 (1786824068) [12285.882536] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 16:01:32 (1786824092) [12296.051382] Key type .llcrypt unregistered [12296.056097] Key type ._llcrypt unregistered [12296.996311] Key type ._llcrypt registered [12297.009443] Key type .llcrypt registered [12297.233187] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [12297.242138] alg: No test for adler32 (adler32-zlib) [12298.368468] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12298.612508] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12300.295459] Key type lgssc registered [12301.454577] Lustre: Echo OBD driver; http://www.lustre.org/ [12326.418385] Key type lgssc unregistered [12326.692619] LNet: 223746:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12326.708494] LNetError: 223746:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12326.722923] LNet: Removed LNI 192.168.201.20@tcp [12327.562378] Key type .llcrypt unregistered [12327.573464] Key type ._llcrypt unregistered [12327.952725] Key type ._llcrypt registered [12327.954813] Key type .llcrypt registered [12329.281493] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 16:02:15 (1786824135) [12341.300580] Key type .llcrypt unregistered [12341.307292] Key type ._llcrypt unregistered [12342.520900] Key type ._llcrypt registered [12342.527161] Key type .llcrypt registered [12343.200514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12343.240741] alg: No test for adler32 (adler32-zlib) [12344.415056] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12344.630228] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12346.352628] Key type lgssc registered [12347.527555] Lustre: Echo OBD driver; http://www.lustre.org/ [12363.741666] Key type lgssc unregistered [12363.999448] LNet: 225304:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12364.008781] LNetError: 225304:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12364.027617] LNet: Removed LNI 192.168.201.20@tcp [12364.731633] Key type .llcrypt unregistered [12364.737355] Key type ._llcrypt unregistered [12371.942628] Key type ._llcrypt registered [12371.944724] Key type .llcrypt registered [12372.503447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12372.528548] alg: No test for adler32 (adler32-zlib) [12373.642367] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12373.973081] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12375.719420] Key type lgssc registered [12376.987232] Lustre: Echo OBD driver; http://www.lustre.org/ [12402.486805] Key type lgssc unregistered [12402.885471] LNet: 227068:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12402.894019] LNetError: 227068:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12402.906849] LNet: Removed LNI 192.168.201.20@tcp [12403.502356] Key type .llcrypt unregistered [12403.505536] Key type ._llcrypt unregistered [12403.969479] Key type ._llcrypt registered [12403.973085] Key type .llcrypt registered [12405.571422] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 16:03:31 (1786824211) [12419.642375] Key type .llcrypt unregistered [12419.643869] Key type ._llcrypt unregistered [12420.949286] Key type ._llcrypt registered [12420.951191] Key type .llcrypt registered [12421.295276] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12421.306412] alg: No test for adler32 (adler32-zlib) [12422.520231] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12422.808870] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12424.463139] Key type lgssc registered [12425.791710] Lustre: Echo OBD driver; http://www.lustre.org/ [12440.347910] Key type lgssc unregistered [12440.635534] LNet: 228622:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12440.640542] LNetError: 228622:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12440.650748] LNet: Removed LNI 192.168.201.20@tcp [12441.433842] Key type .llcrypt unregistered [12441.436179] Key type ._llcrypt unregistered [12448.551761] Key type ._llcrypt registered [12448.559727] Key type .llcrypt registered [12449.045500] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12449.059217] alg: No test for adler32 (adler32-zlib) [12450.107242] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12450.261761] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12451.911348] Key type lgssc registered [12453.235041] Lustre: Echo OBD driver; http://www.lustre.org/ [12469.642515] Key type lgssc unregistered [12469.919741] LNet: 229930:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12469.928836] LNetError: 229930:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12469.956860] LNet: Removed LNI 192.168.201.20@tcp [12470.619831] Key type .llcrypt unregistered [12470.623466] Key type ._llcrypt unregistered [12476.946539] Key type ._llcrypt registered [12476.950871] Key type .llcrypt registered [12477.392523] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12477.405697] alg: No test for adler32 (adler32-zlib) [12478.471487] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12478.756381] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12480.480271] Key type lgssc registered [12481.800584] Lustre: Echo OBD driver; http://www.lustre.org/ [12498.339932] Key type lgssc unregistered [12498.665961] LNet: 231235:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12498.675486] LNetError: 231235:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12498.714705] LNet: Removed LNI 192.168.201.20@tcp [12499.272810] Key type .llcrypt unregistered [12499.278537] Key type ._llcrypt unregistered [12508.577243] Key type ._llcrypt registered [12508.585809] Key type .llcrypt registered [12509.186378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12509.208891] alg: No test for adler32 (adler32-zlib) [12511.116252] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12511.672882] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12513.535272] Key type lgssc registered [12517.589413] Lustre: Echo OBD driver; http://www.lustre.org/ [12545.689351] Key type lgssc unregistered [12546.029446] LNet: 233008:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12546.042533] LNetError: 233008:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12546.060247] LNet: Removed LNI 192.168.201.20@tcp [12546.773792] Key type .llcrypt unregistered [12546.779665] Key type ._llcrypt unregistered [12547.462267] Key type ._llcrypt registered [12547.464018] Key type .llcrypt registered [12549.733987] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 16:05:55 (1786824355) [12563.084328] Key type .llcrypt unregistered [12563.099570] Key type ._llcrypt unregistered [12564.221238] Key type ._llcrypt registered [12564.222894] Key type .llcrypt registered [12564.536565] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12564.562641] alg: No test for adler32 (adler32-zlib) [12565.579548] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12565.887403] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12567.663528] Key type lgssc registered [12569.360786] Lustre: Echo OBD driver; http://www.lustre.org/ [12587.712558] Key type lgssc unregistered [12587.953287] LNet: 234567:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12587.959583] LNetError: 234567:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12587.981223] LNet: Removed LNI 192.168.201.20@tcp [12589.058755] Key type .llcrypt unregistered [12589.064945] Key type ._llcrypt unregistered [12598.697951] Key type ._llcrypt registered [12598.720090] Key type .llcrypt registered [12599.545355] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12599.557748] alg: No test for adler32 (adler32-zlib) [12600.855808] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12601.294902] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12603.223407] Key type lgssc registered [12605.408692] Lustre: Echo OBD driver; http://www.lustre.org/ [12625.942120] Key type lgssc unregistered [12626.141340] LNet: 235885:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12626.147987] LNetError: 235885:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12626.170309] LNet: Removed LNI 192.168.201.20@tcp [12626.990698] Key type .llcrypt unregistered [12626.992276] Key type ._llcrypt unregistered [12635.197468] Key type ._llcrypt registered [12635.198958] Key type .llcrypt registered [12635.617384] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12635.630417] alg: No test for adler32 (adler32-zlib) [12636.823300] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12637.149936] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12638.855357] Key type lgssc registered [12639.968371] Lustre: Echo OBD driver; http://www.lustre.org/ [12655.342882] Key type lgssc unregistered [12655.585292] LNet: 237194:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12655.596966] LNetError: 237194:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12655.611400] LNet: Removed LNI 192.168.201.20@tcp [12656.236404] Key type .llcrypt unregistered [12656.238812] Key type ._llcrypt unregistered [12664.016328] Key type ._llcrypt registered [12664.018055] Key type .llcrypt registered [12664.493363] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12664.502960] alg: No test for adler32 (adler32-zlib) [12665.535860] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12665.729308] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12667.399235] Key type lgssc registered [12668.446464] Lustre: Echo OBD driver; http://www.lustre.org/ [12682.262824] Key type lgssc unregistered [12682.508188] LNet: 238521:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12682.522791] LNetError: 238521:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12682.533249] LNet: Removed LNI 192.168.201.20@tcp [12683.062161] Key type .llcrypt unregistered [12683.064599] Key type ._llcrypt unregistered [12690.284539] Key type ._llcrypt registered [12690.292205] Key type .llcrypt registered [12690.603872] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12690.637660] alg: No test for adler32 (adler32-zlib) [12691.654728] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12691.884539] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12693.527195] Key type lgssc registered [12694.471566] Lustre: Echo OBD driver; http://www.lustre.org/ [12710.173867] Key type lgssc unregistered [12710.370209] LNet: 239833:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12710.379452] LNetError: 239833:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12710.398831] LNet: Removed LNI 192.168.201.20@tcp [12710.979965] Key type .llcrypt unregistered [12710.981489] Key type ._llcrypt unregistered [12719.396283] Key type ._llcrypt registered [12719.416757] Key type .llcrypt registered [12719.689661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12719.711935] alg: No test for adler32 (adler32-zlib) [12720.664307] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12720.894687] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12722.535198] Key type lgssc registered [12723.869024] Lustre: Echo OBD driver; http://www.lustre.org/ [12741.767298] Key type lgssc unregistered [12742.077244] LNet: 241141:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12742.090185] LNetError: 241141:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12742.120308] LNet: Removed LNI 192.168.201.20@tcp [12743.118805] Key type .llcrypt unregistered [12743.121769] Key type ._llcrypt unregistered [12770.304805] Key type ._llcrypt registered [12770.306987] Key type .llcrypt registered [12771.663475] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 16:09:38 (1786824578) [12783.334449] Key type .llcrypt unregistered [12783.336372] Key type ._llcrypt unregistered [12784.528130] Key type ._llcrypt registered [12784.535112] Key type .llcrypt registered [12784.968371] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12784.992809] alg: No test for adler32 (adler32-zlib) [12786.130842] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12786.476759] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12788.303236] Key type lgssc registered [12789.436285] Lustre: Echo OBD driver; http://www.lustre.org/ [12808.444440] Key type lgssc unregistered [12808.828830] LNet: 243473:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12808.836216] LNetError: 243473:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12808.855708] LNet: Removed LNI 192.168.201.20@tcp [12809.589945] Key type .llcrypt unregistered [12809.593904] Key type ._llcrypt unregistered [12818.483817] Key type ._llcrypt registered [12818.486305] Key type .llcrypt registered [12818.679264] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12818.687892] alg: No test for adler32 (adler32-zlib) [12819.877595] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12820.290987] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12822.007241] Key type lgssc registered [12823.224430] Lustre: Echo OBD driver; http://www.lustre.org/ [12838.777312] Key type lgssc unregistered [12839.007901] LNet: 244797:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12839.013834] LNetError: 244797:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12839.042324] LNet: Removed LNI 192.168.201.20@tcp [12839.692199] Key type .llcrypt unregistered [12839.695745] Key type ._llcrypt unregistered [12847.607273] Key type ._llcrypt registered [12847.609700] Key type .llcrypt registered [12848.026429] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12848.038568] alg: No test for adler32 (adler32-zlib) [12849.072716] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12849.413820] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12851.135187] Key type lgssc registered [12852.148361] Lustre: Echo OBD driver; http://www.lustre.org/ [12867.170684] Key type lgssc unregistered [12867.413533] LNet: 246110:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12867.426158] LNetError: 246110:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12867.476916] LNet: Removed LNI 192.168.201.20@tcp [12868.320584] Key type .llcrypt unregistered [12868.328248] Key type ._llcrypt unregistered [12875.639565] Key type ._llcrypt registered [12875.641138] Key type .llcrypt registered [12876.250089] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12876.289336] alg: No test for adler32 (adler32-zlib) [12877.461819] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12877.737413] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12879.439214] Key type lgssc registered [12880.606477] Lustre: Echo OBD driver; http://www.lustre.org/ [12902.607674] Key type lgssc unregistered [12902.841840] LNet: 247922:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12902.848414] LNetError: 247922:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12902.880896] LNet: Removed LNI 192.168.201.20@tcp [12903.451465] Key type .llcrypt unregistered [12903.453758] Key type ._llcrypt unregistered [12903.944678] Key type ._llcrypt registered [12903.945935] Key type .llcrypt registered [12905.559152] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 16:11:51 (1786824711) [12922.614540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12922.633176] alg: No test for adler32 (adler32-zlib) [12923.726772] Lustre: Lustre: Build Version: 2.17.57_3_g67c4c6e [12923.963813] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12925.639869] Key type lgssc registered [12926.885767] Lustre: Echo OBD driver; http://www.lustre.org/ [13146.957593] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 16:15:53 (1786824953) [13148.506494] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [13199.260975] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 12856 sec ========= 16:16:45 (1786825005) [13200.785055] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 16:16:47 (1786825007) === [13205.005797] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 16:16:51 (1786825011) === [13240.984571] Key type lgssc unregistered [13241.198469] LNet: 256982:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13241.204694] LNetError: 256982:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13241.237590] LNet: Removed LNI 192.168.201.20@tcp [13242.068698] Key type .llcrypt unregistered [13242.073740] Key type ._llcrypt unregistered