[ 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 450839034 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.002350] x2apic enabled [ 0.003010] Switched APIC routing to physical x2apic. [ 0.004014] kvm-guest: setup PV IPIs [ 0.006874] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008015] pid_max: default: 32768 minimum: 301 [ 0.009145] LSM: Security Framework initializing [ 0.010064] Yama: becoming mindful. [ 0.011040] SELinux: Initializing. [ 0.012072] *** VALIDATE selinux *** [ 0.020781] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025418] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027107] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028115] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029120] *** VALIDATE tmpfs *** [ 0.031432] *** VALIDATE proc *** [ 0.032256] *** VALIDATE cgroup *** [ 0.034005] *** VALIDATE cgroup2 *** [ 0.035266] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036161] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038027] Spectre V2 : User space: Vulnerable [ 0.039009] Speculative Store Bypass: Vulnerable [ 0.041637] debug: unmapping init [mem 0xffffffff86059000-0xffffffff86060fff] [ 0.043179] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044711] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045023] ... version: 2 [ 0.045929] ... bit width: 48 [ 0.046014] ... generic registers: 4 [ 0.047012] ... value mask: 0000ffffffffffff [ 0.048014] ... max period: 00007fffffffffff [ 0.049017] ... fixed-purpose events: 3 [ 0.050013] ... event mask: 000000070000000f [ 0.051317] rcu: Hierarchical SRCU implementation. [ 0.053531] smp: Bringing up secondary CPUs ... [ 0.054621] x86: Booting SMP configuration: [ 0.055024] .... node #0, CPUs: #1 #2 #3 [ 0.061658] smp: Brought up 1 node, 4 CPUs [ 0.063013] smpboot: Max logical packages: 1 [ 0.064014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.124384] node 0 deferred pages initialised in 58ms [ 0.126414] devtmpfs: initialized [ 0.127179] x86/mm: Memory block size: 128MB [ 0.129262] gcov: version magic: 0x41383552 [ 0.131325] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.133082] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.135232] pinctrl core: initialized pinctrl subsystem [ 0.136132] [ 0.136637] ************************************************************* [ 0.137016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.138008] ** ** [ 0.139010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.140009] ** ** [ 0.141008] ** This means that this kernel is built to expose internal ** [ 0.142009] ** IOMMU data structures, which may compromise security on ** [ 0.143010] ** your system. ** [ 0.144009] ** ** [ 0.145008] ** If you see this message and you are not debugging the ** [ 0.146009] ** kernel, report this immediately to your vendor! ** [ 0.147007] ** ** [ 0.148008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.149008] ************************************************************* [ 0.150513] NET: Registered protocol family 16 [ 0.151430] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.152056] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.153056] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.155015] cpuidle: using governor menu [ 0.156000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.159464] PCI: Using configuration type 1 for base access [ 0.161124] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.172092] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.173021] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.175061] cryptd: max_cpu_qlen set to 1000 [ 0.177158] ACPI: Added _OSI(Module Device) [ 0.178007] ACPI: Added _OSI(Processor Device) [ 0.178925] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.180008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.183160] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.188318] ACPI: Interpreter enabled [ 0.190042] ACPI: PM: (supports S0 S3 S4 S5) [ 0.190938] ACPI: Using IOAPIC for interrupt routing [ 0.191074] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.193274] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.200105] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.202023] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.203015] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.205059] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.209042] acpiphp: Slot [2] registered [ 0.210127] acpiphp: Slot [5] registered [ 0.211120] acpiphp: Slot [6] registered [ 0.213118] acpiphp: Slot [3] registered [ 0.215091] acpiphp: Slot [4] registered [ 0.216095] acpiphp: Slot [7] registered [ 0.217165] acpiphp: Slot [8] registered [ 0.219136] acpiphp: Slot [9] registered [ 0.221092] acpiphp: Slot [10] registered [ 0.222090] acpiphp: Slot [11] registered [ 0.223091] acpiphp: Slot [12] registered [ 0.225082] acpiphp: Slot [13] registered [ 0.226094] acpiphp: Slot [14] registered [ 0.227116] acpiphp: Slot [15] registered [ 0.229098] acpiphp: Slot [16] registered [ 0.230061] acpiphp: Slot [17] registered [ 0.231057] acpiphp: Slot [18] registered [ 0.232008] acpiphp: Slot [19] registered [ 0.232838] acpiphp: Slot [20] registered [ 0.234047] acpiphp: Slot [21] registered [ 0.234887] acpiphp: Slot [22] registered [ 0.236054] acpiphp: Slot [23] registered [ 0.236948] acpiphp: Slot [24] registered [ 0.237045] acpiphp: Slot [25] registered [ 0.237902] acpiphp: Slot [26] registered [ 0.239107] acpiphp: Slot [27] registered [ 0.239924] acpiphp: Slot [28] registered [ 0.240067] acpiphp: Slot [29] registered [ 0.240974] acpiphp: Slot [30] registered [ 0.242080] acpiphp: Slot [31] registered [ 0.242879] PCI host bridge to bus 0000:00 [ 0.244012] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.245040] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.247017] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.249017] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.251014] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.253019] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.254195] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.256775] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.258866] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.265013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.268545] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.270012] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.271009] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.274014] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.275459] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.277506] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.279025] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.280595] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.283011] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.290015] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.293012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.297900] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.307019] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.312011] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.324011] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.333181] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.338017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.343018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.355018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.362853] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.365252] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.366265] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.368273] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.370260] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.374028] iommu: Default domain type: Passthrough [ 0.375347] SCSI subsystem initialized [ 0.377099] ACPI: bus type USB registered [ 0.377969] usbcore: registered new interface driver usbfs [ 0.379055] usbcore: registered new interface driver hub [ 0.380044] usbcore: registered new device driver usb [ 0.381094] pps_core: LinuxPPS API ver. 1 registered [ 0.381994] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.383038] PTP clock support registered [ 0.385187] EDAC MC: Ver: 3.0.0 [ 0.386295] PCI: Using ACPI for IRQ routing [ 0.388687] NetLabel: Initializing [ 0.390013] NetLabel: domain hash size = 128 [ 0.391009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.393072] NetLabel: unlabeled traffic allowed by default [ 0.395135] vgaarb: loaded [ 0.396272] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.398013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.405991] clocksource: Switched to clocksource kvm-clock [ 0.507103] VFS: Disk quotas dquot_6.6.0 [ 0.508691] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.511498] *** VALIDATE ramfs *** [ 0.512742] *** VALIDATE hugetlbfs *** [ 0.514229] pnp: PnP ACPI init [ 0.517194] pnp: PnP ACPI: found 6 devices [ 0.533518] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.536689] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.538731] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.540960] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.543205] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.545734] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.548418] NET: Registered protocol family 2 [ 0.550600] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.555023] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.558555] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.563797] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.567513] TCP: Hash tables configured (established 65536 bind 65536) [ 0.570530] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.573637] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.576237] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.579333] NET: Registered protocol family 1 [ 0.582353] RPC: Registered named UNIX socket transport module. [ 0.584384] RPC: Registered udp transport module. [ 0.585503] RPC: Registered tcp transport module. [ 0.586577] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.588734] NET: Registered protocol family 44 [ 0.590407] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.592822] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.595688] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.598114] PCI: CLS 0 bytes, default 64 [ 0.600180] Unpacking initramfs... [ 1.978340] debug: unmapping init [mem 0xffff942efcc64000-0xffff942efffcffff] [ 1.982029] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.983831] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 1.986466] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.497977] Initialise system trusted keyrings [ 2.499865] Key type blacklist registered [ 2.501991] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.511419] zbud: loaded [ 2.514482] *** VALIDATE nfs *** [ 2.515781] *** VALIDATE nfs4 *** [ 2.517505] pstore: using deflate compression [ 2.521415] Platform Keyring initialized [ 2.630525] NET: Registered protocol family 38 [ 2.633227] Key type asymmetric registered [ 2.635306] Asymmetric key parser 'x509' registered [ 2.638638] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.643916] io scheduler mq-deadline registered [ 2.646322] io scheduler kyber registered [ 2.648110] io scheduler bfq registered [ 2.650443] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.653390] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.656140] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.659069] ACPI: Power Button [PWRF] [ 2.664412] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.671384] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.681783] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.708849] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.737664] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.742580] Non-volatile memory driver v1.3 [ 2.744308] Linux agpgart interface v0.103 [ 2.775494] virtio_blk virtio1: [vda] 136552 512-byte logical blocks (69.9 MB/66.7 MiB) [ 2.777850] vda: detected capacity change from 0 to 69914624 [ 2.802745] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.806165] vdb: detected capacity change from 0 to 1073741824 [ 2.818731] libphy: Fixed MDIO Bus: probed [ 2.826078] usbcore: registered new interface driver usbserial_generic [ 2.828811] usbserial: USB Serial support registered for generic [ 2.831213] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.835519] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.837071] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.839078] mousedev: PS/2 mouse device common for all mice [ 2.842120] rtc_cmos 00:05: RTC can wake from S4 [ 2.845507] rtc_cmos 00:05: registered as rtc0 [ 2.847280] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.850068] intel_pstate: CPU model not supported [ 2.850079] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.856444] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.859470] hid: raw HID events driver (C) Jiri Kosina [ 2.860846] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.862907] usbcore: registered new interface driver usbhid [ 2.868699] usbhid: USB HID core driver [ 2.871521] drop_monitor: Initializing network drop monitor service [ 2.874167] Initializing XFRM netlink socket [ 2.878205] NET: Registered protocol family 10 [ 2.882855] Segment Routing with IPv6 [ 2.884489] NET: Registered protocol family 17 [ 2.887114] mpls_gso: MPLS GSO support [ 2.896767] RAS: Correctable Errors collector initialized. [ 2.898222] AVX version of gcm_enc/dec engaged. [ 2.899277] AES CTR mode by8 optimization enabled [ 2.974399] sched_clock: Marking stable (2974310789, 0)->(3845384929, -871074140) [ 2.978224] registered taskstats version 1 [ 2.980251] Loading compiled-in X.509 certificates [ 2.982287] zswap: loaded using pool lzo/zbud [ 3.009085] Key type big_key registered [ 3.023516] Key type encrypted registered [ 3.025606] ima: No TPM chip found, activating TPM-bypass! [ 3.028038] ima: Allocated hash algorithm: sha1 [ 3.030644] ima: No architecture policies found [ 3.033104] evm: Initialising EVM extended attributes: [ 3.035109] evm: security.selinux [ 3.036747] evm: security.ima [ 3.038422] evm: security.capability [ 3.040297] evm: HMAC attrs: 0x1 [ 3.043072] rtc_cmos 00:05: setting system clock to 2026-06-11 22:35:06 UTC (1781217306) [ 3.050263] debug: unmapping init [mem 0xffffffff87003000-0xffffffff871fffff] [ 3.054522] debug: unmapping init [mem 0xffffffff85d82000-0xffffffff86058fff] [ 3.062129] Write protecting the kernel read-only data: 28672k [ 3.066654] debug: unmapping init [mem 0xffffffff84403000-0xffffffff845fffff] [ 3.070743] debug: unmapping init [mem 0xffffffff84d14000-0xffffffff84dfffff] [ 3.101940] 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.111612] systemd[1]: Detected virtualization kvm. [ 3.114067] systemd[1]: Detected architecture x86-64. [ 3.115893] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.145276] systemd[1]: No hostname configured. [ 3.147522] systemd[1]: Set hostname to . [ 3.150103] random: systemd: uninitialized urandom read (16 bytes read) [ 3.153580] systemd[1]: Initializing machine ID from random generator. [ 3.197656] random: ln: uninitialized urandom read (6 bytes read) [ 3.356645] random: systemd: uninitialized urandom read (16 bytes read) [ 3.362930] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.370752] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.387739] systemd[1]: Starting Setup Virtual Console... Starting Setup Virtual Console... Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Journal Service. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.293502] device-mapper: uevent: version 1.0.3 [ 5.298419] 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. [ 7.129217] random: fast init done Starting dracut initqueue hook... [ 7.206596] virtio_net virtio0 ens2: renamed from eth0 [ 7.540958] scsi host0: ata_piix [ 7.598852] scsi host1: ata_piix [ 7.602797] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.608023] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.553354] random: crng init done [ 12.559819] random: 7 urandom warning(s) missed due to ratelimiting [ 15.080943] dracut-initqueue[600]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 16.504804] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 19.731418] printk: systemd: 17 output lines suppressed due to ratelimiting [ 20.494184] SELinux: Disabled at runtime. [ 20.582672] 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) [ 20.594448] systemd[1]: Detected virtualization kvm. [ 20.598569] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 22.505676] systemd[1]: initrd-switch-root.service: Succeeded. [ 22.513804] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 22.524268] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 22.530451] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 22.534352] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 22.543682] systemd[1]: Starting Journal Service... Starting Journal Service... [ 22.556616] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ OK ] Listening on RPCbind Server Activation Socket. Starting Remount Root and Kernel File Systems... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Activating swap /dev/disk/by-label/SWAP... Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target RPC Port Mapper. [ 22.989257] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice system-getty.slice. Mounting Kernel Debug File System... Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 24.340739] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 25.199437] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 25.449402] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 25.837893] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 25.882368] EDAC sbridge: Ver: 1.1.2 [ 29.254884] Key type dns_resolver registered [ 29.896790] NFS: Registering the id_resolver key type [ 29.900512] Key type id_resolver registered [ 29.903271] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ 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... Starting Hostname Service... [ OK ] Started Login Service. [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg236-client login: [ 109.407423] libcfs: loading out-of-tree module taints kernel. [ 109.694868] Key type ._llcrypt registered [ 109.696144] Key type .llcrypt registered [ 110.479973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 110.521374] alg: No test for adler32 (adler32-zlib) [ 112.282273] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 113.175104] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 114.951402] Key type lgssc registered [ 116.483208] hrtimer: interrupt took 2527905 ns [ 117.142195] Lustre: Echo OBD driver; http://www.lustre.org/ [ 304.156495] Lustre: Mounted lustre-client [ 309.256154] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 325.486051] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing check_logdir /tmp/testlogs/ [ 329.697874] Lustre: lustre-OST0000-osc-ffff942f509ff800: disconnect after 23s idle [ 331.352656] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing yml_node [ 334.692780] Lustre: DEBUG MARKER: Client: 2.17.53.75 [ 337.526669] Lustre: DEBUG MARKER: MDS: 2.17.53.75 [ 339.924548] Lustre: DEBUG MARKER: OSS: 2.17.53.75 [ 341.602951] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Jun 11 18:40:43 EDT 2026 [ 362.777848] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 364.564619] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 368.221568] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 378.549796] Lustre: Unmounted lustre-client [ 494.574445] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 498.954414] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 526.254332] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 570.096027] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 18:44:31 (1781217871) [ 573.713742] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 576.439438] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 18:44:37 (1781217877) [ 613.298554] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 618.374680] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 638.782277] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 639.723958] Lustre: Mounted lustre-client [ 645.095378] Lustre: Unmounted lustre-client [ 677.659408] Key type lgssc unregistered [ 678.005847] LNet: 14885:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 678.030409] LNetError: 14885:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 678.092300] LNet: Removed LNI 192.168.202.36@tcp [ 679.156372] Key type .llcrypt unregistered [ 679.158094] Key type ._llcrypt unregistered [ 700.357495] Key type ._llcrypt registered [ 700.359866] Key type .llcrypt registered [ 701.775237] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 18:46:43 (1781218003) [ 703.897798] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 703.916927] alg: No test for adler32 (adler32-zlib) [ 705.056740] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 705.326981] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 706.999276] Key type lgssc registered [ 708.717964] Lustre: Echo OBD driver; http://www.lustre.org/ [ 795.029943] Lustre: Mounted lustre-client [ 881.829860] Lustre: Unmounted lustre-client [ 913.984961] Key type lgssc unregistered [ 914.254413] LNet: 18691:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 914.262076] LNetError: 18691:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 914.285459] LNet: Removed LNI 192.168.202.36@tcp [ 915.264716] Key type .llcrypt unregistered [ 915.271151] Key type ._llcrypt unregistered [ 927.940642] Key type ._llcrypt registered [ 927.943352] Key type .llcrypt registered [ 929.729972] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 18:50:31 (1781218231) [ 932.445058] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 932.476864] alg: No test for adler32 (adler32-zlib) [ 933.840640] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 934.226725] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 935.943261] Key type lgssc registered [ 937.740931] Lustre: Echo OBD driver; http://www.lustre.org/ [ 993.788842] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 998.312575] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1023.798270] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1026.755949] Lustre: Mounted lustre-client [ 1043.989692] Lustre: Unmounted lustre-client [ 1084.477453] Key type lgssc unregistered [ 1084.724545] LNet: 22205:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1084.739428] LNetError: 22205:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1084.773351] LNet: Removed LNI 192.168.202.36@tcp [ 1085.549119] Key type .llcrypt unregistered [ 1085.551966] Key type ._llcrypt unregistered [ 1097.139641] Key type ._llcrypt registered [ 1097.142362] Key type .llcrypt registered [ 1098.733943] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 18:53:20 (1781218400) [ 1101.911081] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1101.929327] alg: No test for adler32 (adler32-zlib) [ 1103.107943] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 1103.400095] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 1105.127246] Key type lgssc registered [ 1106.735939] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1161.995213] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1165.890482] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1185.335943] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1187.953208] Lustre: Mounted lustre-client [ 1195.190882] Lustre: Unmounted lustre-client [ 1225.443296] Key type lgssc unregistered [ 1225.749057] LNet: 25303:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1225.759134] LNetError: 25303:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1225.778409] LNet: Removed LNI 192.168.202.36@tcp [ 1226.329501] Key type .llcrypt unregistered [ 1226.332157] Key type ._llcrypt unregistered [ 1242.363560] Key type ._llcrypt registered [ 1242.366103] Key type .llcrypt registered [ 1243.797608] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 18:55:45 (1781218545) [ 1246.373240] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1246.467182] alg: No test for adler32 (adler32-zlib) [ 1247.796349] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 1248.221594] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 1249.999469] Key type lgssc registered [ 1251.648693] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1314.090585] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1318.794886] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1341.905552] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1354.297449] Lustre: Mounted lustre-client [ 1364.485202] Lustre: lustre-MDT0000-mdc-ffff942f50b7b000: Connection to lustre-MDT0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1374.708688] Lustre: lustre-MDT0001-mdc-ffff942f50b7b000: Connection to lustre-MDT0001 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1378.279957] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1385.951149] Lustre: 26265:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781218673/real 1781218673] req@00000000f854bfb6 x1867743023340160/t0(0) o400->MGC192.168.202.136@tcp@192.168.202.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1781218689 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1385.975598] LustreError: MGC192.168.202.136@tcp: Connection to MGS (at 192.168.202.136@tcp) was lost; in progress operations using this service will fail [ 1388.678437] Lustre: Unmounted lustre-client [ 1418.038516] Key type lgssc unregistered [ 1418.283977] LNet: 28872:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1418.297687] LNetError: 28872:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1418.315760] LNet: Removed LNI 192.168.202.36@tcp [ 1419.180514] Key type .llcrypt unregistered [ 1419.182329] Key type ._llcrypt unregistered [ 1440.084868] Key type ._llcrypt registered [ 1440.086757] Key type .llcrypt registered [ 1441.820480] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 18:59:03 (1781218743) [ 1444.742421] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1444.830315] alg: No test for adler32 (adler32-zlib) [ 1446.232547] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 1446.578408] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 1448.271810] Key type lgssc registered [ 1449.670196] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1502.812885] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1507.730781] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1533.344442] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1536.253694] Lustre: Mounted lustre-client [ 1541.630326] Lustre: lustre-OST0000-osc-ffff942f510b6000: Connection to lustre-OST0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1550.112209] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1560.554822] Lustre: Unmounted lustre-client [ 1591.070259] Key type lgssc unregistered [ 1591.452805] LNet: 32407:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1591.464941] LNetError: 32407:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1592.488752] LNet: Removed LNI 192.168.202.36@tcp [ 1593.452721] Key type .llcrypt unregistered [ 1593.462341] Key type ._llcrypt unregistered [ 1613.348870] Key type ._llcrypt registered [ 1613.353777] Key type .llcrypt registered [ 1615.104190] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 19:01:56 (1781218916) [ 1619.167067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1619.267908] alg: No test for adler32 (adler32-zlib) [ 1620.459241] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 1620.764623] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 1622.480855] Key type lgssc registered [ 1623.917562] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1679.591864] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1683.812139] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1708.473904] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1710.404216] Lustre: Mounted lustre-client [ 1717.411649] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1721.386475] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1733.345849] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-06-1119h03m54s [ 1735.876288] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1741.294814] Lustre: lustre-MDT0000-mdc-ffff942f51012800: Connection to lustre-MDT0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1748.099370] LustreError: 36106:0:(lmv_obd.c:1450:lmv_statfs()) lustre-MDT0000-mdc-ffff942f51012800: can't stat MDS #0: rc = -19 [ 1750.134242] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1759.748241] LustreError: MGC192.168.202.136@tcp: Connection to MGS (at 192.168.202.136@tcp) was lost; in progress operations using this service will fail [ 1759.788470] Lustre: Evicted from MGS (at 192.168.202.136@tcp) after server handle changed from 0x26d6c490cfdac287 to 0x26d6c490cfdac551 [ 1759.813203] Lustre: MGC192.168.202.136@tcp: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [ 1760.847666] LustreError: lustre-MDT0000-mdc-ffff942f51012800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1760.877608] Lustre: lustre-MDT0000-mdc-ffff942f51012800: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [ 1769.223501] Lustre: DEBUG MARKER: Wait for df (36106) ... [ 1771.088521] Lustre: DEBUG MARKER: done [ 1774.387122] Lustre: Unmounted lustre-client [ 1813.205810] Key type lgssc unregistered [ 1813.586734] LNet: 37123:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1813.591648] LNetError: 37123:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1813.619552] LNet: Removed LNI 192.168.202.36@tcp [ 1814.714245] Key type .llcrypt unregistered [ 1814.716720] Key type ._llcrypt unregistered [ 1852.505704] Key type ._llcrypt registered [ 1852.510554] Key type .llcrypt registered [ 1854.412215] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 19:05:55 (1781219155) [ 1858.224147] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1858.339162] alg: No test for adler32 (adler32-zlib) [ 1859.760251] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 1860.127115] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 1861.967345] Key type lgssc registered [ 1863.809675] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1936.178278] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1941.995103] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1967.477191] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1969.586794] Lustre: Mounted lustre-client [ 1974.310799] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-06-1119h07m56s [ 1977.202975] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1987.307494] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1990.698757] Lustre: DEBUG MARKER: Creating a test file and stat it [ 2011.103698] Lustre: 41180:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781219294/real 1781219294] req@000000005a8ee29e x1867743665068928/t0(0) o101->lustre-MDT0000-mdc-ffff942f51a13800@192.168.202.136@tcp:12/10 lens 576/1152 e 0 to 1 dl 1781219314 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 2011.150416] Lustre: lustre-MDT0000-mdc-ffff942f51a13800: Connection to lustre-MDT0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2011.204250] Lustre: lustre-MDT0000-mdc-ffff942f51a13800: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [ 2033.002915] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 2038.172168] Lustre: DEBUG MARKER: done [ 2043.752984] Lustre: Unmounted lustre-client [ 2077.641530] Key type lgssc unregistered [ 2078.062838] LNet: 42069:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2078.092401] LNetError: 42069:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2079.143742] LNet: Removed LNI 192.168.202.36@tcp [ 2079.893318] Key type .llcrypt unregistered [ 2079.895240] Key type ._llcrypt unregistered [ 2122.599457] Key type ._llcrypt registered [ 2122.601164] Key type .llcrypt registered [ 2124.239693] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 19:10:25 (1781219425) [ 2126.292477] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2128.726164] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 19:10:30 (1781219430) [ 2134.019412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2134.118124] alg: No test for adler32 (adler32-zlib) [ 2135.498938] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 2135.893408] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 2137.719190] Key type lgssc registered [ 2139.443600] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2171.584384] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 19:11:12 (1781219472) [ 2215.321190] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2220.268803] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2245.768150] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2247.704534] Lustre: Mounted lustre-client [ 2252.795432] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2257.530426] Lustre: Unmounted lustre-client [ 2283.946432] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2333.169300] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2338.437582] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2339.446437] Lustre: Mounted lustre-client [ 2347.227527] Lustre: Unmounted lustre-client [ 2416.421978] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2422.075680] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2422.852292] Lustre: Mounted lustre-client [ 2427.885261] Lustre: Unmounted lustre-client [ 2455.637350] Lustre: DEBUG MARKER: files compared the same [ 2482.772864] Key type lgssc unregistered [ 2483.174197] LNet: 49496:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2483.191765] LNetError: 49496:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2483.230977] LNet: Removed LNI 192.168.202.36@tcp [ 2484.373838] Key type .llcrypt unregistered [ 2484.380702] Key type ._llcrypt unregistered [ 2505.450751] Key type ._llcrypt registered [ 2505.453924] Key type .llcrypt registered [ 2507.685914] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 19:16:48 (1781219808) [ 2511.506408] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2511.566394] alg: No test for adler32 (adler32-zlib) [ 2512.790406] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 2513.145656] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 2514.831503] Key type lgssc registered [ 2516.658226] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2575.640479] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2579.994732] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2604.735074] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2606.622663] Lustre: Mounted lustre-client [ 2616.805860] Lustre: Unmounted lustre-client [ 2651.411398] Key type lgssc unregistered [ 2651.778614] LNet: 52997:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2651.792972] LNetError: 52997:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2651.817396] LNet: Removed LNI 192.168.202.36@tcp [ 2652.579592] Key type .llcrypt unregistered [ 2652.587552] Key type ._llcrypt unregistered [ 2673.434546] Key type ._llcrypt registered [ 2673.438186] Key type .llcrypt registered [ 2675.309350] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 19:19:36 (1781219976) [ 2678.605680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2678.647712] alg: No test for adler32 (adler32-zlib) [ 2679.885737] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 2680.137639] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 2681.951304] Key type lgssc registered [ 2683.717540] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2774.581724] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2812.138752] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2816.877505] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2846.292938] Key type lgssc unregistered [ 2846.675666] LNet: 56399:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2846.687728] LNetError: 56399:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2846.718335] LNet: Removed LNI 192.168.202.36@tcp [ 2847.514705] Key type .llcrypt unregistered [ 2847.516436] Key type ._llcrypt unregistered [ 2865.147740] Key type ._llcrypt registered [ 2865.152216] Key type .llcrypt registered [ 2866.827667] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 19:22:48 (1781220168) [ 2870.781554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2870.836676] alg: No test for adler32 (adler32-zlib) [ 2871.980674] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 2872.308058] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 2874.063759] Key type lgssc registered [ 2876.143694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2965.007920] Lustre: Mounted lustre-client [ 2972.430078] Lustre: Unmounted lustre-client [ 3018.400833] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 19:25:20 (1781220320) [ 3162.892584] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3183.247684] Lustre: Mounted lustre-client [ 3191.347485] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3191.351685] LustreError: 62850:0:(file.c:252:ll_close_inode_openhandle()) lustre-clilmv-ffff942f45898000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3191.395345] Lustre: 57382:0:(llite_lib.c:4198:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.136@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3201.809233] Lustre: Unmounted lustre-client [ 3259.802338] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 19:29:21 (1781220561) [ 3273.491792] Key type lgssc unregistered [ 3273.811975] LNet: 64075:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3273.829136] LNetError: 64075:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3273.849564] LNet: Removed LNI 192.168.202.36@tcp [ 3274.751136] Key type .llcrypt unregistered [ 3274.753402] Key type ._llcrypt unregistered [ 3288.995814] Key type ._llcrypt registered [ 3288.999046] Key type .llcrypt registered [ 3289.660389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3289.719698] alg: No test for adler32 (adler32-zlib) [ 3290.949680] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 3291.294111] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 3292.999168] Key type lgssc registered [ 3294.557537] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3348.241516] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3352.463774] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3373.687917] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3376.700853] Lustre: Mounted lustre-client [ 3381.046167] Lustre: Unmounted lustre-client [ 3413.285966] Key type lgssc unregistered [ 3413.497924] LNet: 67003:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3413.502951] LNetError: 67003:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3413.520327] LNet: Removed LNI 192.168.202.36@tcp [ 3414.291044] Key type .llcrypt unregistered [ 3414.294912] Key type ._llcrypt unregistered [ 3427.889475] Key type ._llcrypt registered [ 3427.894774] Key type .llcrypt registered [ 3428.762229] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3428.821023] alg: No test for adler32 (adler32-zlib) [ 3430.008968] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 3430.343850] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 3432.071917] Key type lgssc registered [ 3433.825900] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3549.409667] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3553.860492] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3574.785686] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3577.548424] Lustre: Mounted lustre-client [ 3580.394376] Lustre: Unmounted lustre-client [ 3614.017941] Key type lgssc unregistered [ 3614.366891] LNet: 70349:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3614.383369] LNetError: 70349:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3614.407479] LNet: Removed LNI 192.168.202.36@tcp [ 3615.339216] Key type .llcrypt unregistered [ 3615.346817] Key type ._llcrypt unregistered [ 3634.160409] Key type ._llcrypt registered [ 3634.163738] Key type .llcrypt registered [ 3635.762694] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 19:35:37 (1781220937) [ 3638.497651] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3638.597148] alg: No test for adler32 (adler32-zlib) [ 3639.811219] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 3640.117301] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 3641.879227] Key type lgssc registered [ 3643.160650] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3702.083834] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3707.097577] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3729.694541] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3732.552693] Lustre: Mounted lustre-client [ 3742.197581] Lustre: Unmounted lustre-client [ 3742.665085] Lustre: Mounted lustre-client [ 3746.085389] Lustre: Unmounted lustre-client [ 3776.974369] Key type lgssc unregistered [ 3777.425388] LNet: 73948:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3777.438761] LNetError: 73948:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3777.486146] LNet: Removed LNI 192.168.202.36@tcp [ 3778.397127] Key type .llcrypt unregistered [ 3778.399112] Key type ._llcrypt unregistered [ 3796.451560] Key type ._llcrypt registered [ 3796.455826] Key type .llcrypt registered [ 3797.287614] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3797.309206] alg: No test for adler32 (adler32-zlib) [ 3798.966786] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 3799.494467] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 3801.359691] Key type lgssc registered [ 3803.824222] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3862.562647] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3867.099643] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3889.547892] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3891.330104] Lustre: Mounted lustre-client [ 3899.132509] Lustre: Unmounted lustre-client [ 3929.481483] Key type lgssc unregistered [ 3929.801399] LNet: 76901:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3929.812967] LNetError: 76901:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3929.827697] LNet: Removed LNI 192.168.202.36@tcp [ 3930.669397] Key type .llcrypt unregistered [ 3930.677477] Key type ._llcrypt unregistered [ 3950.250981] Key type ._llcrypt registered [ 3950.253921] Key type .llcrypt registered [ 3951.655819] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 19:40:53 (1781221253) [ 3955.563756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3955.643275] alg: No test for adler32 (adler32-zlib) [ 3956.818890] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 3957.142927] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 3958.887615] Key type lgssc registered [ 3960.887030] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4020.283519] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4024.674333] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4047.039881] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4048.997680] Lustre: Mounted lustre-client [ 4056.602912] Lustre: lustre: root_squash is set to 0:0 [ 4062.572469] Lustre: lustre: nosquash_nids is cleared [ 4067.792036] Lustre: lustre: root_squash is set to 500:500 [ 4075.438519] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.36@tcp 192.168.0.[2,10]@tcp [ 4083.277690] Lustre: lustre: nosquash_nids set to 192.168.202.36/24@tcp [ 4096.816229] Lustre: Unmounted lustre-client [ 4128.304641] Key type lgssc unregistered [ 4128.609269] LNet: 80749:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4128.617990] LNetError: 80749:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4128.637180] LNet: Removed LNI 192.168.202.36@tcp [ 4129.305828] Key type .llcrypt unregistered [ 4129.307304] Key type ._llcrypt unregistered [ 4149.073273] Key type ._llcrypt registered [ 4149.077548] Key type .llcrypt registered [ 4151.000595] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 19:44:12 (1781221452) [ 4154.741581] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 4157.283651] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 19:44:18 (1781221458) [ 4160.955255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4160.972560] alg: No test for adler32 (adler32-zlib) [ 4162.137701] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 4162.334471] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 4164.071270] Key type lgssc registered [ 4165.966188] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4225.338705] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4230.518804] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4256.862172] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4257.593830] Lustre: lustre: root_squash is set to 500:500 [ 4257.640206] Lustre: lustre: nosquash_nids set to 192.168.202.36/24@tcp [ 4268.101508] Lustre: Mounted lustre-client [ 4274.832557] Lustre: Unmounted lustre-client [ 4309.720154] Key type lgssc unregistered [ 4310.140962] LNet: 84629:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4310.145854] LNetError: 84629:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4310.177391] LNet: Removed LNI 192.168.202.36@tcp [ 4311.218596] Key type .llcrypt unregistered [ 4311.220927] Key type ._llcrypt unregistered [ 4331.566633] Key type ._llcrypt registered [ 4331.569331] Key type .llcrypt registered [ 4333.407213] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4335.614842] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 19:47:17 (1781221637) [ 4357.747540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4357.807275] alg: No test for adler32 (adler32-zlib) [ 4359.039748] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 4359.386179] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 4361.055232] Key type lgssc registered [ 4362.545611] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4456.262245] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4461.560918] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4486.704300] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4570.984564] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4575.260266] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4601.333483] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4613.950265] Lustre: Mounted lustre-client [ 4648.227430] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff942f47505000.ost_server_uuid 50 [ 4650.447317] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff942f47505000.ost_server_uuid in IDLE state after 0 sec [ 4651.152108] Lustre: Mounted lustre-client [ 4657.325182] Lustre: Unmounted lustre-client [ 4659.686054] Lustre: Unmounted lustre-client [ 4703.408615] Key type lgssc unregistered [ 4703.752715] LNet: 92108:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4703.759489] LNetError: 92108:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4703.780936] LNet: Removed LNI 192.168.202.36@tcp [ 4704.379995] Key type .llcrypt unregistered [ 4704.382170] Key type ._llcrypt unregistered [ 4742.001811] Key type ._llcrypt registered [ 4742.003339] Key type .llcrypt registered [ 4744.436334] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 19:54:05 (1781222045) [ 4772.450695] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4772.476757] alg: No test for adler32 (adler32-zlib) [ 4773.847179] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 4774.332510] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 4776.095469] Key type lgssc registered [ 4778.484143] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4875.946585] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4880.505552] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4908.055762] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4909.895482] Lustre: Mounted lustre-client [ 4931.552098] Lustre: 93487:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781222218/real 1781222218] req@000000009272f776 x1867746720617600/t0(0) o400->lustre-OST0000-osc-ffff942f50072000@192.168.202.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1781222234 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4931.602050] Lustre: lustre-OST0000-osc-ffff942f50072000: Connection to lustre-OST0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4935.712308] Lustre: 93487:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781222223/real 1781222223] req@000000004149fe12 x1867746720618112/t0(0) o400->lustre-OST0000-osc-ffff942f50072000@192.168.202.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1781222239 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4940.770693] Lustre: 93487:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781222228/real 1781222228] req@00000000fcd0ecbd x1867746720618624/t0(0) o400->lustre-OST0000-osc-ffff942f50072000@192.168.202.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1781222244 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4947.936275] Lustre: 93486:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781222234/real 1781222234] req@000000004cd4915c x1867746720619136/t0(0) o400->lustre-OST0000-osc-ffff942f50072000@192.168.202.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1781222250 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4952.051480] Lustre: lustre-MDT0000-mdc-ffff942f50072000: Connection to lustre-MDT0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4968.418200] Lustre: 93488:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781222255/real 1781222255] req@00000000ebfced45 x1867746720621568/t0(0) o400->MGC192.168.202.136@tcp@192.168.202.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1781222271 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4968.461939] LustreError: MGC192.168.202.136@tcp: Connection to MGS (at 192.168.202.136@tcp) was lost; in progress operations using this service will fail [ 4977.641162] Lustre: Evicted from MGS (at 192.168.202.136@tcp) after server handle changed from 0xc81de8b863b97a17 to 0xc81de8b863b97b9f [ 4977.666649] Lustre: MGC192.168.202.136@tcp: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [ 4981.273832] Lustre: lustre-OST0000-osc-ffff942f50072000: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [ 4986.425113] Lustre: lustre-MDT0000-mdc-ffff942f50072000: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [ 4995.098732] Lustre: Unmounted lustre-client [ 5026.953612] Key type lgssc unregistered [ 5027.302919] LNet: 97709:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5027.309955] LNetError: 97709:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5027.329808] LNet: Removed LNI 192.168.202.36@tcp [ 5028.125114] Key type .llcrypt unregistered [ 5028.127866] Key type ._llcrypt unregistered [ 5047.339654] Key type ._llcrypt registered [ 5047.342374] Key type .llcrypt registered [ 5049.355291] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 19:59:10 (1781222350) [ 5064.389078] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5064.500788] alg: No test for adler32 (adler32-zlib) [ 5065.755139] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 5066.149210] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 5067.887465] Key type lgssc registered [ 5070.145490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5113.689298] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5233.839465] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5238.517173] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5263.898466] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5266.594320] Lustre: Mounted lustre-client [ 5268.970858] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5271.276484] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 20:02:52 (1781222572) [ 5545.294876] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5555.693828] Lustre: Unmounted lustre-client [ 5691.824473] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5697.241453] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5726.762369] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5772.719673] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 20:11:14 (1781223074) [ 5886.194757] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5890.989107] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5914.865181] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5915.664058] Lustre: Mounted lustre-client [ 5921.268249] Lustre: Unmounted lustre-client [ 5960.905872] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 20:14:21 (1781223261) [ 6079.389665] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6084.068574] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6111.405295] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6112.207784] Lustre: Mounted lustre-client [ 6118.730129] Lustre: Unmounted lustre-client [ 6150.951173] Key type lgssc unregistered [ 6151.306225] LNet: 120386:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6151.314810] LNetError: 120386:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6151.325950] LNet: Removed LNI 192.168.202.36@tcp [ 6152.546944] Key type .llcrypt unregistered [ 6152.552972] Key type ._llcrypt unregistered [ 6173.327210] Key type ._llcrypt registered [ 6173.330703] Key type .llcrypt registered [ 6174.856498] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 20:17:56 (1781223476) [ 6178.321722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6178.402710] alg: No test for adler32 (adler32-zlib) [ 6179.784946] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 6180.254745] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 6182.103372] Key type lgssc registered [ 6184.122754] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6259.754632] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6265.782805] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6291.342559] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6297.668407] Lustre: Mounted lustre-client [ 6311.824037] Lustre: Unmounted lustre-client [ 6343.408959] Key type lgssc unregistered [ 6343.679966] LNet: 123902:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6343.693093] LNetError: 123902:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6343.726468] LNet: Removed LNI 192.168.202.36@tcp [ 6344.761895] Key type .llcrypt unregistered [ 6344.764273] Key type ._llcrypt unregistered [ 6364.144996] Key type ._llcrypt registered [ 6364.148293] Key type .llcrypt registered [ 6365.802234] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 20:21:07 (1781223667) [ 6368.832221] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6368.875619] alg: No test for adler32 (adler32-zlib) [ 6370.305624] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 6370.747739] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 6372.519174] Key type lgssc registered [ 6374.278480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6437.715840] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6443.111484] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6469.681956] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6476.867831] Lustre: Mounted lustre-client [ 6487.035214] Lustre: lustre-OST0000-osc-ffff942f46b69800: Connection to lustre-OST0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6497.074293] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff942f46b69800.ost_server_uuid 50 [ 6499.984295] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6512.780451] Lustre: Unmounted lustre-client [ 6538.229644] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 20:23:59 (1781223839) [ 6580.825734] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6586.680151] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6609.532915] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6636.038398] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6637.265477] Lustre: Mounted lustre-client [ 6642.683995] Lustre: lustre-OST0000-osc-ffff942f510b1800: Connection to lustre-OST0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6673.238630] Lustre: Unmounted lustre-client [ 6725.847029] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 20:27:07 (1781224027) [ 6763.676748] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6768.160037] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6791.255658] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6815.966980] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6816.547355] Lustre: Mounted lustre-client [ 6821.863531] Lustre: lustre-OST0000-osc-ffff942f477bd000: Connection to lustre-OST0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6838.447497] Lustre: Unmounted lustre-client [ 6900.148272] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 20:30:01 (1781224201) [ 7022.839444] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7027.596433] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7056.792203] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7140.774346] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7145.527131] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7167.149864] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7201.640066] Lustre: Mounted lustre-client [ 7247.807630] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7250.259614] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7252.885944] Lustre: Unmounted lustre-client [ 7288.778459] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 20:36:30 (1781224590) [ 7331.014590] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7335.649504] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7358.367558] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7397.364804] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7433.360086] Lustre: Mounted lustre-client [ 7482.308634] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7484.589971] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7499.277815] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7509.669105] Lustre: Unmounted lustre-client [ 7576.018656] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 20:41:17 (1781224877) [ 7616.649049] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7622.958844] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7649.760959] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7650.539314] Lustre: Mounted lustre-client [ 7681.617757] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7684.018871] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7701.648419] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff942f510b5000.ost_server_uuid 50 [ 7703.682352] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff942f510b5000.ost_server_uuid in IDLE state after 0 sec [ 7708.725592] Lustre: Unmounted lustre-client [ 7709.248833] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7709.251625] Lustre: Skipped 3 previous similar messages [ 7709.364069] Lustre: Mounted lustre-client [ 7710.795253] LustreError: 146570:0:(sec.c:413:import_sec_validate_get()) import 00000000ace6a40e (NEW) with no sec [ 7791.425112] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 20:44:52 (1781225092) [ 7832.778201] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7838.093220] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7863.722718] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7891.692687] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7892.423748] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7892.714207] Lustre: Mounted lustre-client [ 7905.840118] Lustre: Unmounted lustre-client [ 7905.851864] Lustre: Skipped 1 previous similar message [ 7958.701784] Key type lgssc unregistered [ 7959.085624] LNet: 149860:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7959.098214] LNetError: 149860:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7959.115509] LNet: Removed LNI 192.168.202.36@tcp [ 7960.140821] Key type .llcrypt unregistered [ 7960.142297] Key type ._llcrypt unregistered [ 7981.284051] Key type ._llcrypt registered [ 7981.285503] Key type .llcrypt registered [ 7983.188949] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 20:48:04 (1781225284) [ 7984.431490] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7984.574505] alg: No test for adler32 (adler32-zlib) [ 7985.787537] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 7986.112478] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 7987.895323] Key type lgssc registered [ 7989.747413] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8053.160456] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8058.337905] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8081.982061] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8106.253930] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8107.142677] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8108.316828] Lustre: Mounted lustre-client [ 8113.334734] LustreError: lustre-MDT0001-mdc-ffff942f50071800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 8113.373637] Lustre: lustre-MDT0001-mdc-ffff942f50071800: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [ 8118.358721] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8121.203505] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8148.524883] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8154.500238] Lustre: Unmounted lustre-client [ 8192.133756] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 20:51:33 (1781225493) [ 8320.609061] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8326.275630] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8354.607631] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8355.572683] Lustre: Mounted lustre-client [ 8389.703875] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8392.609322] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8406.799892] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8417.186384] Lustre: Unmounted lustre-client [ 8464.685890] Key type lgssc unregistered [ 8465.131859] LNet: 159232:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8465.143464] LNetError: 159232:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8465.164553] LNet: Removed LNI 192.168.202.36@tcp [ 8466.254703] Key type .llcrypt unregistered [ 8466.256814] Key type ._llcrypt unregistered [ 8505.915944] Key type ._llcrypt registered [ 8505.924558] Key type .llcrypt registered [ 8508.116652] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 20:56:48 (1781225808) [ 8512.390983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8512.430048] alg: No test for adler32 (adler32-zlib) [ 8513.651839] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 8513.946716] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 8515.770228] Key type lgssc registered [ 8518.240225] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8583.300979] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8587.943590] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8612.130757] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8614.059336] Lustre: Mounted lustre-client [ 8665.922302] Lustre: Unmounted lustre-client [ 8718.580840] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8719.434067] Lustre: Mounted lustre-client [ 8757.425096] Lustre: Unmounted lustre-client [ 8789.285667] Key type lgssc unregistered [ 8789.553964] LNet: 163590:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8789.559851] LNetError: 163590:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8789.575345] LNet: Removed LNI 192.168.202.36@tcp [ 8790.406101] Key type .llcrypt unregistered [ 8790.413996] Key type ._llcrypt unregistered [ 8810.515336] Key type ._llcrypt registered [ 8810.517084] Key type .llcrypt registered [ 8812.280380] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 21:01:53 (1781226113) [ 8815.240271] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8815.301522] alg: No test for adler32 (adler32-zlib) [ 8816.598941] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 8816.870857] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 8818.592492] Key type lgssc registered [ 8820.037901] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8886.261103] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8891.838138] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8918.475888] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8921.610468] Lustre: Mounted lustre-client [ 8944.305831] Lustre: Unmounted lustre-client [ 8975.910403] Key type lgssc unregistered [ 8976.410617] LNet: 167259:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8976.425675] LNetError: 167259:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8976.444691] LNet: Removed LNI 192.168.202.36@tcp [ 8977.450744] Key type .llcrypt unregistered [ 8977.457846] Key type ._llcrypt unregistered [ 8996.932223] Key type ._llcrypt registered [ 8996.940129] Key type .llcrypt registered [ 8997.313301] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8997.323226] alg: No test for adler32 (adler32-zlib) [ 8998.587315] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 8998.836790] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 9000.487213] Key type lgssc registered [ 9002.312388] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9058.462165] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9063.703536] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9088.095776] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9090.170606] Lustre: Mounted lustre-client [ 9102.538976] Lustre: Unmounted lustre-client [ 9130.398532] Key type lgssc unregistered [ 9130.653314] LNet: 170239:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9130.661374] LNetError: 170239:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9130.681724] LNet: Removed LNI 192.168.202.36@tcp [ 9131.229335] Key type .llcrypt unregistered [ 9131.250929] Key type ._llcrypt unregistered [ 9148.030651] Key type ._llcrypt registered [ 9148.031813] Key type .llcrypt registered [ 9149.421960] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 21:07:31 (1781226451) [ 9152.035272] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9152.132226] alg: No test for adler32 (adler32-zlib) [ 9153.506150] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 9153.763679] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 9155.536868] Key type lgssc registered [ 9157.228846] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9218.377465] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9223.050054] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9246.754329] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9248.558888] Lustre: Mounted lustre-client [ 9276.480188] Lustre: Unmounted lustre-client [ 9307.066190] Key type lgssc unregistered [ 9307.508195] LNet: 173906:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9307.526110] LNetError: 173906:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9307.565505] LNet: Removed LNI 192.168.202.36@tcp [ 9308.569866] Key type .llcrypt unregistered [ 9308.585689] Key type ._llcrypt unregistered [ 9322.867450] Key type ._llcrypt registered [ 9322.869205] Key type .llcrypt registered [ 9323.597425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9323.610174] alg: No test for adler32 (adler32-zlib) [ 9324.775797] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 9324.989498] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 9326.727162] Key type lgssc registered [ 9327.945188] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9387.369425] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9393.926344] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9419.235785] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9431.708077] Lustre: Mounted lustre-client [ 9441.777733] Lustre: Unmounted lustre-client [ 9472.992312] Key type lgssc unregistered [ 9473.256614] LNet: 176889:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9473.260884] LNetError: 176889:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9474.284801] LNet: Removed LNI 192.168.202.36@tcp [ 9475.198081] Key type .llcrypt unregistered [ 9475.199534] Key type ._llcrypt unregistered [ 9492.914095] Key type ._llcrypt registered [ 9492.915645] Key type .llcrypt registered [ 9494.294596] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 21:13:16 (1781226796) [ 9520.530724] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9520.820346] alg: No test for adler32 (adler32-zlib) [ 9522.051920] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 9522.366147] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 9524.079705] Key type lgssc registered [ 9525.839713] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9625.353228] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9629.839258] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9657.790256] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9706.186642] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 21:16:47 (1781227007) [ 9743.684736] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9748.454771] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9772.945802] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9775.149099] Lustre: Mounted lustre-client [ 9786.360089] Lustre: Unmounted lustre-client [ 9815.993418] Key type lgssc unregistered [ 9816.388186] LNet: 183817:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9816.393938] LNetError: 183817:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9816.432576] LNet: Removed LNI 192.168.202.36@tcp [ 9817.324588] Key type .llcrypt unregistered [ 9817.326768] Key type ._llcrypt unregistered [ 9838.453762] Key type ._llcrypt registered [ 9838.458105] Key type .llcrypt registered [ 9840.582657] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9842.559767] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 21:19:04 (1781227144) [ 9864.557199] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9864.586410] alg: No test for adler32 (adler32-zlib) [ 9865.833591] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [ 9866.170236] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [ 9867.935450] Key type lgssc registered [ 9869.414195] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9980.671564] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9985.129704] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10008.113264] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [10028.832593] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [10031.625880] Lustre: Mounted lustre-client [10057.744348] Lustre: Unmounted lustre-client [10209.852487] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10214.565473] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10238.910233] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10291.721815] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 21:26:33 (1781227593) [10293.661534] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [10295.688772] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 21:26:37 (1781227597) [10395.685841] Key type lgssc unregistered [10396.038084] LNet: 195075:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10396.055344] LNetError: 195075:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10396.082623] LNet: Removed LNI 192.168.202.36@tcp [10397.083103] Key type .llcrypt unregistered [10397.086325] Key type ._llcrypt unregistered [10414.898452] Key type ._llcrypt registered [10414.900927] Key type .llcrypt registered [10416.690919] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 21:28:38 (1781227718) [10465.536188] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10465.550729] alg: No test for adler32 (adler32-zlib) [10466.828029] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [10467.249485] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [10469.096175] Key type lgssc registered [10470.798932] Lustre: Echo OBD driver; http://www.lustre.org/ [10523.772779] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10528.280756] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10563.786961] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10601.657445] Key type lgssc unregistered [10601.957646] LNet: 198728:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10601.972570] LNetError: 198728:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10601.993145] LNet: Removed LNI 192.168.202.36@tcp [10602.707484] Key type .llcrypt unregistered [10602.711929] Key type ._llcrypt unregistered [10621.723369] Key type ._llcrypt registered [10621.724841] Key type .llcrypt registered [10623.651925] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 21:32:04 (1781227924) [10626.617483] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10626.770729] alg: No test for adler32 (adler32-zlib) [10627.923275] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [10628.186502] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [10629.903448] Key type lgssc registered [10631.298551] Lustre: Echo OBD driver; http://www.lustre.org/ [10678.977388] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10683.095297] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10708.561074] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10711.609222] Lustre: Mounted lustre-client [10726.908371] Lustre: lustre-MDT0000-mdc-ffff942f509f8800: Connection to lustre-MDT0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [10742.252125] Lustre: lustre-MDT0001-mdc-ffff942f509f8800: Connection to lustre-MDT0001 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [10748.383892] Lustre: 199719:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781228035/real 1781228035] req@00000000081cb8ef x1867752859058944/t0(0) o400->MGC192.168.202.136@tcp@192.168.202.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1781228051 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10748.424232] LustreError: MGC192.168.202.136@tcp: Connection to MGS (at 192.168.202.136@tcp) was lost; in progress operations using this service will fail [10772.977978] Lustre: Evicted from MGS (at 192.168.202.136@tcp) after server handle changed from 0xde2186e87f5b97ea to 0xde2186e87f5bb3ea [10773.029617] Lustre: MGC192.168.202.136@tcp: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [10778.613137] Lustre: 201760:0:(mgc_request.c:1901:mgc_process_log()) MGC192.168.202.136@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [10801.646148] LustreError: lustre-MDT0000-mdc-ffff942f509f8800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10801.765066] Lustre: lustre-MDT0000-mdc-ffff942f509f8800: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [10812.257957] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10814.813782] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10819.750195] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10821.548779] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10825.643142] Lustre: Unmounted lustre-client [10854.875124] Key type lgssc unregistered [10855.207808] LNet: 203551:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10855.228784] LNetError: 203551:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10855.256904] LNet: Removed LNI 192.168.202.36@tcp [10856.140758] Key type .llcrypt unregistered [10856.143703] Key type ._llcrypt unregistered [10875.167541] Key type ._llcrypt registered [10875.169677] Key type .llcrypt registered [10876.877553] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 21:36:18 (1781228178) [10880.466424] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10880.495618] alg: No test for adler32 (adler32-zlib) [10881.639330] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [10881.932585] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [10883.631201] Key type lgssc registered [10885.314964] Lustre: Echo OBD driver; http://www.lustre.org/ [10940.037798] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10945.076034] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11015.734503] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11021.648464] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11059.346646] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11081.370811] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [11123.186749] Key type lgssc unregistered [11123.480256] LNet: 208673:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11123.489347] LNetError: 208673:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11123.504241] LNet: Removed LNI 192.168.202.36@tcp [11124.382454] Key type .llcrypt unregistered [11124.385333] Key type ._llcrypt unregistered [11172.343489] Key type ._llcrypt registered [11172.351665] Key type .llcrypt registered [11174.100451] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 21:41:15 (1781228475) [11218.848957] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11218.864225] alg: No test for adler32 (adler32-zlib) [11220.171818] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [11220.483376] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [11222.335259] Key type lgssc registered [11224.109968] Lustre: Echo OBD driver; http://www.lustre.org/ [11315.628776] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11319.767928] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11342.074602] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11386.869747] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 21:44:48 (1781228688) [11397.638200] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 21:44:58 (1781228698) [11438.371813] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11443.153356] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11464.286044] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11467.223763] Lustre: Mounted lustre-client [11477.221377] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [11480.904971] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [11482.886485] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [11485.127864] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [11487.721968] Lustre: lustre-MDT0000-mdc-ffff942f436b2800: Connection to lustre-MDT0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [11509.220962] Lustre: 210572:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781228796/real 1781228796] req@00000000ef092aca x1867753479744512/t0(0) o400->MGC192.168.202.136@tcp@192.168.202.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1781228812 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11509.264708] LustreError: MGC192.168.202.136@tcp: Connection to MGS (at 192.168.202.136@tcp) was lost; in progress operations using this service will fail [11519.526504] Lustre: Evicted from MGS (at 192.168.202.136@tcp) after server handle changed from 0xca06a41f819f06fa to 0xca06a41f819f0970 [11519.564255] Lustre: MGC192.168.202.136@tcp: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [11523.693053] Lustre: lustre-MDT0000-mdc-ffff942f436b2800: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [11534.514701] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11537.041472] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11541.851788] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [11545.077206] Lustre: lustre-MDT0000-mdc-ffff942f436b2800: Connection to lustre-MDT0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [11561.311791] Lustre: 210574:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781228848/real 1781228848] req@0000000042a61e31 x1867753479753344/t0(0) o400->MGC192.168.202.136@tcp@192.168.202.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1781228864 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11561.337972] LustreError: MGC192.168.202.136@tcp: Connection to MGS (at 192.168.202.136@tcp) was lost; in progress operations using this service will fail [11570.676454] Lustre: Evicted from MGS (at 192.168.202.136@tcp) after server handle changed from 0xca06a41f819f0970 to 0xca06a41f819f0b84 [11570.695221] Lustre: MGC192.168.202.136@tcp: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [11580.024859] Lustre: lustre-MDT0000-mdc-ffff942f436b2800: Connection restored to 192.168.202.136@tcp (at 192.168.202.136@tcp) [11591.195597] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11593.139180] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11597.680376] Lustre: Unmounted lustre-client [11676.682186] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11681.078546] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11706.404763] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11707.190327] Lustre: Mounted lustre-client [11710.652492] Lustre: Unmounted lustre-client [11760.312906] Key type lgssc unregistered [11760.637946] LNet: 220860:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11760.642432] LNetError: 220860:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11760.669377] LNet: Removed LNI 192.168.202.36@tcp [11761.813921] Key type .llcrypt unregistered [11761.822201] Key type ._llcrypt unregistered [11784.197920] Key type ._llcrypt registered [11784.200173] Key type .llcrypt registered [11785.613308] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 21:51:27 (1781229087) [11792.253822] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11792.427129] alg: No test for adler32 (adler32-zlib) [11793.860742] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [11794.186883] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [11795.991724] Key type lgssc registered [11797.977490] Lustre: Echo OBD driver; http://www.lustre.org/ [11860.717596] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11865.537747] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11888.917211] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11891.695607] Lustre: Mounted lustre-client [11897.871414] Lustre: Unmounted lustre-client [11972.696885] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11977.020564] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11999.724580] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12000.465965] Lustre: Mounted lustre-client [12010.535160] Lustre: Unmounted lustre-client [12086.383231] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12090.858857] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12112.218766] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12113.480410] Lustre: Mounted lustre-client [12116.233221] Lustre: Unmounted lustre-client [12145.819918] Key type lgssc unregistered [12146.051994] LNet: 227654:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12146.057667] LNetError: 227654:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12146.079290] LNet: Removed LNI 192.168.202.36@tcp [12147.020179] Key type .llcrypt unregistered [12147.022863] Key type ._llcrypt unregistered [12163.995428] Key type ._llcrypt registered [12163.997379] Key type .llcrypt registered [12165.472535] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 21:57:47 (1781229467) [12168.808951] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12168.854448] alg: No test for adler32 (adler32-zlib) [12169.918494] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [12170.266122] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [12172.048834] Key type lgssc registered [12173.588669] Lustre: Echo OBD driver; http://www.lustre.org/ [12220.432729] Key type lgssc unregistered [12220.785523] LNet: 229978:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12220.797577] LNetError: 229978:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12220.813807] LNet: Removed LNI 192.168.202.36@tcp [12222.062601] Key type .llcrypt unregistered [12222.067555] Key type ._llcrypt unregistered [12253.265232] Key type ._llcrypt registered [12253.266878] Key type .llcrypt registered [12253.855693] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12253.884859] alg: No test for adler32 (adler32-zlib) [12255.108112] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [12255.368187] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [12257.088359] Key type lgssc registered [12258.533912] Lustre: Echo OBD driver; http://www.lustre.org/ [12345.318294] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12349.991167] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12374.858535] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12423.029971] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [12425.157844] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 22:02:06 (1781229726) [12460.309402] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12464.660535] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12484.252995] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12508.034220] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [12509.906497] Lustre: Mounted lustre-client [12515.336267] Lustre: lustre-OST0001-osc-ffff942f46b49800: Connection to lustre-OST0001 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [12523.453900] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12533.886234] Lustre: Unmounted lustre-client [12579.560246] Key type lgssc unregistered [12579.938408] LNet: 236907:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12579.945556] LNetError: 236907:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12579.969940] LNet: Removed LNI 192.168.202.36@tcp [12581.072666] Key type .llcrypt unregistered [12581.077836] Key type ._llcrypt unregistered [12622.527724] Key type ._llcrypt registered [12622.536601] Key type .llcrypt registered [12624.890402] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 22:05:25 (1781229925) [12644.052701] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12644.286447] alg: No test for adler32 (adler32-zlib) [12645.647368] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [12645.979546] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [12647.681254] Key type lgssc registered [12649.199606] Lustre: Echo OBD driver; http://www.lustre.org/ [12704.836674] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12709.472833] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12739.902957] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 22:07:22 (1781230042) [12773.077811] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12776.916803] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12803.417969] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12806.297764] Lustre: Mounted lustre-client [12817.974501] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12826.240541] Lustre: Unmounted lustre-client [12940.118409] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12944.373066] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12994.638380] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12995.972688] Lustre: Mounted lustre-client [12999.662754] Lustre: Unmounted lustre-client [13025.108477] Key type lgssc unregistered [13025.397395] LNet: 244366:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13025.404226] LNetError: 244366:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13025.419846] LNet: Removed LNI 192.168.202.36@tcp [13026.171905] Key type .llcrypt unregistered [13026.174653] Key type ._llcrypt unregistered [13056.323561] Key type ._llcrypt registered [13056.342207] Key type .llcrypt registered [13056.882588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13056.902288] alg: No test for adler32 (adler32-zlib) [13058.234149] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [13058.591835] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [13060.319178] Key type lgssc registered [13061.770222] Lustre: Echo OBD driver; http://www.lustre.org/ [13146.543917] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13150.382338] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13171.466130] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13213.512309] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [13215.569805] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 22:15:17 (1781230517) [13252.571467] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13256.555432] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13276.732479] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13281.810856] Lustre: Mounted lustre-client [13286.401691] Lustre: Unmounted lustre-client [13315.342757] Key type lgssc unregistered [13315.580898] LNet: 250828:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13315.592722] LNetError: 250828:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13315.637485] LNet: Removed LNI 192.168.202.36@tcp [13316.233735] Key type .llcrypt unregistered [13316.236421] Key type ._llcrypt unregistered [13331.788919] Key type ._llcrypt registered [13331.797753] Key type .llcrypt registered [13333.089751] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [13334.510534] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 22:17:16 (1781230636) [13343.663649] Key type .llcrypt unregistered [13343.668828] Key type ._llcrypt unregistered [13351.762246] Key type ._llcrypt registered [13351.765375] Key type .llcrypt registered [13352.287388] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13352.330286] alg: No test for adler32 (adler32-zlib) [13353.528630] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [13353.824345] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [13355.552686] Key type lgssc registered [13356.817256] Lustre: Echo OBD driver; http://www.lustre.org/ [13410.643442] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13425.330979] Lustre: Mounted lustre-client [13427.539032] Lustre: Unmounted lustre-client [13455.423631] Key type lgssc unregistered [13455.800352] LNet: 254247:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13455.813903] LNetError: 254247:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13455.834865] LNet: Removed LNI 192.168.202.36@tcp [13456.682338] Key type .llcrypt unregistered [13456.684600] Key type ._llcrypt unregistered [13477.350541] Key type ._llcrypt registered [13477.352358] Key type .llcrypt registered [13478.623035] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 22:19:40 (1781230780) [13481.834266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13481.866221] alg: No test for adler32 (adler32-zlib) [13483.133441] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [13483.608809] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [13485.471175] Key type lgssc registered [13487.154291] Lustre: Echo OBD driver; http://www.lustre.org/ [13573.428302] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13601.688915] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13605.439748] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13607.928258] Lustre: Mounted lustre-client [13610.165231] Lustre: Unmounted lustre-client [13639.298798] Key type lgssc unregistered [13639.685928] LNet: 257699:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13639.691500] LNetError: 257699:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13639.713327] LNet: Removed LNI 192.168.202.36@tcp [13640.395926] Key type .llcrypt unregistered [13640.398509] Key type ._llcrypt unregistered [13662.368816] Key type ._llcrypt registered [13662.372707] Key type .llcrypt registered [13663.899615] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 22:22:45 (1781230965) [13665.851105] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13665.876314] alg: No test for adler32 (adler32-zlib) [13666.952260] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [13667.198415] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [13668.880666] Key type lgssc registered [13670.188472] Lustre: Echo OBD driver; http://www.lustre.org/ [13718.710514] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13723.379880] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13742.121358] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13744.869464] Lustre: Mounted lustre-client [13750.259437] Lustre: lustre-MDT0000-mdc-ffff942f5127b000: Connection to lustre-MDT0000 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [13754.714833] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13754.744776] LustreError: 260767:0:(file.c:6109:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13755.143627] LustreError: MGC192.168.202.136@tcp: Connection to MGS (at 192.168.202.136@tcp) was lost; in progress operations using this service will fail [13755.192627] Lustre: Unmounted lustre-client [13786.465832] Key type lgssc unregistered [13786.815782] LNet: 261180:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13786.825738] LNetError: 261180:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13786.861342] LNet: Removed LNI 192.168.202.36@tcp [13787.480292] Key type .llcrypt unregistered [13787.481878] Key type ._llcrypt unregistered [13803.483527] Key type ._llcrypt registered [13803.489708] Key type .llcrypt registered [13804.756550] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 22:25:06 (1781231106) [13807.239728] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13807.363227] alg: No test for adler32 (adler32-zlib) [13808.580911] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [13808.966341] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [13810.657183] Key type lgssc registered [13812.073436] Lustre: Echo OBD driver; http://www.lustre.org/ [13860.437709] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13865.002197] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13885.810405] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13887.485254] Lustre: Mounted lustre-client [13892.584744] Lustre: lustre-MDT0001-mdc-ffff942f50138800: Connection to lustre-MDT0001 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [13898.538684] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13902.238030] Lustre: Unmounted lustre-client [13923.115554] Key type lgssc unregistered [13923.390891] LNet: 264665:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13923.404577] LNetError: 264665:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13923.443725] LNet: Removed LNI 192.168.202.36@tcp [13924.335085] Key type .llcrypt unregistered [13924.339881] Key type ._llcrypt unregistered [13946.084247] Key type ._llcrypt registered [13946.093952] Key type .llcrypt registered [13947.485907] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 22:27:29 (1781231249) [13968.281909] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13968.297862] alg: No test for adler32 (adler32-zlib) [13969.521687] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [13969.713621] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [13971.391187] Key type lgssc registered [13972.419663] Lustre: Echo OBD driver; http://www.lustre.org/ [14081.058215] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14083.806786] Lustre: Mounted lustre-client [14124.525650] Lustre: lustre-MDT0001-mdc-ffff942f5013b800: Connection to lustre-MDT0001 (at 192.168.202.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [14134.198796] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [14144.587084] Lustre: Unmounted lustre-client [14172.969670] Key type lgssc unregistered [14173.257325] LNet: 270422:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14173.266722] LNetError: 270422:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14173.282213] LNet: Removed LNI 192.168.202.36@tcp [14174.201275] Key type .llcrypt unregistered [14174.206602] Key type ._llcrypt unregistered [14194.937753] Key type ._llcrypt registered [14194.943186] Key type .llcrypt registered [14196.518701] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 22:31:38 (1781231498) [14198.302394] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [14200.521578] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 22:31:42 (1781231502) [14203.683706] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [14206.587655] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 22:31:47 (1781231507) [14208.563493] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [14210.606576] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 22:31:52 (1781231512) [14212.806281] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [14215.005595] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 22:31:56 (1781231516) [14216.675747] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [14218.811393] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 22:32:00 (1781231520) [14229.530439] Key type .llcrypt unregistered [14229.533249] Key type ._llcrypt unregistered [14239.576833] Key type ._llcrypt registered [14239.578508] Key type .llcrypt registered [14240.322616] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14240.336648] alg: No test for adler32 (adler32-zlib) [14241.765906] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [14242.061312] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [14243.873848] Key type lgssc registered [14245.380340] Lustre: Echo OBD driver; http://www.lustre.org/ [14329.559733] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14333.579819] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14358.088987] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14360.042590] Lustre: Mounted lustre-client [14364.023786] Lustre: Unmounted lustre-client [14406.503504] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 22:35:08 (1781231708) [14438.611505] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14443.357938] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14478.049348] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14489.187492] Lustre: Mounted lustre-client [14492.016031] Lustre: Unmounted lustre-client [14530.915898] LNet: 1 local NIs in recovery (showing 1): 192.168.202.36@tcp [14538.494371] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 22:37:20 (1781231840) [15196.019914] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [15200.696519] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [15233.120252] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [15328.013893] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 22:50:30 (1781232630) [15339.607412] Key type lgssc unregistered [15339.898342] LNet: 283698:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15339.905673] LNetError: 283698:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [15340.967569] LNet: Removed LNI 192.168.202.36@tcp [15341.710355] Key type .llcrypt unregistered [15341.714182] Key type ._llcrypt unregistered [15478.661757] Key type ._llcrypt registered [15478.686607] Key type .llcrypt registered [15478.999349] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15479.025956] alg: No test for adler32 (adler32-zlib) [15480.152934] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [15480.424740] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [15482.071193] Key type lgssc registered [15483.288797] Lustre: Echo OBD driver; http://www.lustre.org/ [15572.740835] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [15576.720510] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [15597.077084] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [15630.754305] Key type lgssc unregistered [15631.032989] LNet: 287309:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15631.044108] LNetError: 287309:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [15631.058140] LNet: Removed LNI 192.168.202.36@tcp [15631.714621] Key type .llcrypt unregistered [15631.716400] Key type ._llcrypt unregistered [15669.506601] Key type ._llcrypt registered [15669.513693] Key type .llcrypt registered [15671.618389] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 22:56:12 (1781232972) [15694.279542] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15694.324809] alg: No test for adler32 (adler32-zlib) [15695.589322] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [15695.918464] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [15697.543290] Key type lgssc registered [15698.933711] Lustre: Echo OBD driver; http://www.lustre.org/ [15749.850880] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 22:57:31 (1781233051) [15761.492922] Key type lgssc unregistered [15761.807514] LNet: 290871:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15761.829556] LNetError: 290871:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [15761.852663] LNet: Removed LNI 192.168.202.36@tcp [15762.775729] Key type .llcrypt unregistered [15762.786219] Key type ._llcrypt unregistered [15773.977848] Key type ._llcrypt registered [15773.981943] Key type .llcrypt registered [15774.510958] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15774.527412] alg: No test for adler32 (adler32-zlib) [15775.661343] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [15775.975308] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [15777.679384] Key type lgssc registered [15779.405677] Lustre: Echo OBD driver; http://www.lustre.org/ [15834.976690] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [15838.819209] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [15859.717889] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [16138.686205] LNet: Added LNI 192.168.202.36@tcp50 [8/256/0/180] [16142.308155] LNet: Removed LNI 192.168.202.36@tcp [16144.486212] Lustre: 295204:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff942f436b4000: config has no valid NIDs, force dynamic NIDs [16145.713092] Lustre: Mounted lustre-client [16145.915189] Lustre: Unmounted lustre-client [16148.877939] Key type lgssc unregistered [16149.234502] LNet: 295462:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16149.299381] LNetError: 295462:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [16149.332335] LNet: Removed LNI 192.168.202.36@tcp50 [16150.029339] Key type .llcrypt unregistered [16150.032222] Key type ._llcrypt unregistered [16167.927740] Key type ._llcrypt registered [16167.932785] Key type .llcrypt registered [16168.645171] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16168.659236] alg: No test for adler32 (adler32-zlib) [16169.854848] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [16170.272326] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [16171.960038] Key type lgssc registered [16173.731785] Lustre: Echo OBD driver; http://www.lustre.org/ [16233.753796] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [16238.580392] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [16247.017467] LNet: Added LNI 192.168.202.36@tcp50 [8/256/0/180] [16247.925145] LNet: Removed LNI 192.168.202.36@tcp [16249.108110] Lustre: 298097:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff942f50b77000: config has no valid NIDs, force dynamic NIDs [16250.372565] Lustre: Mounted lustre-client [16250.970945] Lustre: Unmounted lustre-client [16286.858874] Key type lgssc unregistered [16287.316885] LNet: 298966:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16287.320867] LNetError: 298966:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [16287.331846] LNet: Removed LNI 192.168.202.36@tcp50 [16287.935583] Key type .llcrypt unregistered [16287.940662] Key type ._llcrypt unregistered [16299.183818] Key type ._llcrypt registered [16299.190227] Key type .llcrypt registered [16301.206586] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 23:06:42 (1781233602) [16322.939736] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16322.951859] alg: No test for adler32 (adler32-zlib) [16324.412236] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [16324.966895] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [16326.727359] Key type lgssc registered [16328.177885] Lustre: Echo OBD driver; http://www.lustre.org/ [16415.922833] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [16419.403639] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [16442.673770] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [16480.203553] Key type lgssc unregistered [16480.652766] LNet: 303625:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16480.664568] LNetError: 303625:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [16480.700827] LNet: Removed LNI 192.168.202.36@tcp [16481.821714] Key type .llcrypt unregistered [16481.824510] Key type ._llcrypt unregistered [16496.220514] Key type ._llcrypt registered [16496.224885] Key type .llcrypt registered [16497.943258] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 23:09:59 (1781233799) [16501.923576] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16501.955936] alg: No test for adler32 (adler32-zlib) [16503.289471] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [16503.539879] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [16505.247378] Key type lgssc registered [16506.855670] Lustre: Echo OBD driver; http://www.lustre.org/ [16559.650348] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [16564.058279] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [16585.129385] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [16587.923991] Lustre: Mounted lustre-client [16649.633853] Lustre: Mounted fs15246-client [16668.550695] Lustre: Unmounted fs15246-client [16700.600386] Lustre: Unmounted lustre-client [16738.414810] Key type lgssc unregistered [16738.715129] LNet: 308401:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16738.729145] LNetError: 308401:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [16738.742574] LNet: Removed LNI 192.168.202.36@tcp [16739.443707] Key type .llcrypt unregistered [16739.446075] Key type ._llcrypt unregistered [16751.106697] Key type ._llcrypt registered [16751.108304] Key type .llcrypt registered [16752.965757] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 23:14:14 (1781234054) [16755.814729] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16755.915062] alg: No test for adler32 (adler32-zlib) [16757.097675] Lustre: Lustre: Build Version: 2.17.53_75_g2ada427 [16757.671883] LNet: Added LNI 192.168.202.36@tcp [8/256/0/180] [16759.567179] Key type lgssc registered [16761.498611] Lustre: Echo OBD driver; http://www.lustre.org/ [16903.385786] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [16907.162122] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [16928.210928] Lustre: DEBUG MARKER: oleg236-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [17038.007504] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16694 sec ========= 23:18:59 (1781234339) [17039.727762] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 23:19:01 (1781234341) === [17043.228254] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 23:19:05 (1781234345) === [17079.557343] Key type lgssc unregistered [17079.870471] LNet: 317576:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17079.878488] LNetError: 317576:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [17079.898310] LNet: Removed LNI 192.168.202.36@tcp [17080.831745] Key type .llcrypt unregistered [17080.839588] Key type ._llcrypt unregistered