[ 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 435239924 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.002366] x2apic enabled [ 0.003011] Switched APIC routing to physical x2apic. [ 0.004017] kvm-guest: setup PV IPIs [ 0.007398] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008027] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009017] pid_max: default: 32768 minimum: 301 [ 0.010155] LSM: Security Framework initializing [ 0.011062] Yama: becoming mindful. [ 0.012037] SELinux: Initializing. [ 0.013109] *** VALIDATE selinux *** [ 0.020629] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026058] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027162] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028083] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029136] *** VALIDATE tmpfs *** [ 0.030523] *** VALIDATE proc *** [ 0.032128] *** VALIDATE cgroup *** [ 0.033017] *** VALIDATE cgroup2 *** [ 0.035144] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036146] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037013] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038034] Spectre V2 : User space: Vulnerable [ 0.039013] Speculative Store Bypass: Vulnerable [ 0.042213] debug: unmapping init [mem 0xffffffffbbc59000-0xffffffffbbc60fff] [ 0.045175] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046739] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047027] ... version: 2 [ 0.048012] ... bit width: 48 [ 0.049012] ... generic registers: 4 [ 0.050014] ... value mask: 0000ffffffffffff [ 0.051018] ... max period: 00007fffffffffff [ 0.052015] ... fixed-purpose events: 3 [ 0.053012] ... event mask: 000000070000000f [ 0.054320] rcu: Hierarchical SRCU implementation. [ 0.056527] smp: Bringing up secondary CPUs ... [ 0.057534] x86: Booting SMP configuration: [ 0.058026] .... node #0, CPUs: #1 #2 #3 [ 0.061555] smp: Brought up 1 node, 4 CPUs [ 0.063014] smpboot: Max logical packages: 1 [ 0.064021] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.195018] node 0 deferred pages initialised in 129ms [ 0.199191] devtmpfs: initialized [ 0.200300] x86/mm: Memory block size: 128MB [ 0.202820] gcov: version magic: 0x41383552 [ 0.204264] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.205090] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.206259] pinctrl core: initialized pinctrl subsystem [ 0.207205] [ 0.207785] ************************************************************* [ 0.208026] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.209021] ** ** [ 0.210019] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.211030] ** ** [ 0.212022] ** This means that this kernel is built to expose internal ** [ 0.213021] ** IOMMU data structures, which may compromise security on ** [ 0.214022] ** your system. ** [ 0.215014] ** ** [ 0.216016] ** If you see this message and you are not debugging the ** [ 0.217031] ** kernel, report this immediately to your vendor! ** [ 0.218026] ** ** [ 0.219025] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.220026] ************************************************************* [ 0.221955] NET: Registered protocol family 16 [ 0.222825] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.223096] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.224106] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.226162] cpuidle: using governor menu [ 0.228199] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.231852] PCI: Using configuration type 1 for base access [ 0.234143] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.245270] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.248083] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.252138] cryptd: max_cpu_qlen set to 1000 [ 0.256298] ACPI: Added _OSI(Module Device) [ 0.258050] ACPI: Added _OSI(Processor Device) [ 0.260022] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.261019] ACPI: Added _OSI(Processor Aggregator Device) [ 0.266322] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.273522] ACPI: Interpreter enabled [ 0.275111] ACPI: PM: (supports S0 S3 S4 S5) [ 0.276018] ACPI: Using IOAPIC for interrupt routing [ 0.279276] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.283555] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.295958] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.299076] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.302026] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.304169] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.310000] acpiphp: Slot [2] registered [ 0.311132] acpiphp: Slot [5] registered [ 0.313237] acpiphp: Slot [6] registered [ 0.315232] acpiphp: Slot [3] registered [ 0.317175] acpiphp: Slot [4] registered [ 0.319166] acpiphp: Slot [7] registered [ 0.321193] acpiphp: Slot [8] registered [ 0.323195] acpiphp: Slot [9] registered [ 0.325212] acpiphp: Slot [10] registered [ 0.326174] acpiphp: Slot [11] registered [ 0.328208] acpiphp: Slot [12] registered [ 0.330171] acpiphp: Slot [13] registered [ 0.332145] acpiphp: Slot [14] registered [ 0.333132] acpiphp: Slot [15] registered [ 0.334115] acpiphp: Slot [16] registered [ 0.335121] acpiphp: Slot [17] registered [ 0.336169] acpiphp: Slot [18] registered [ 0.338137] acpiphp: Slot [19] registered [ 0.339147] acpiphp: Slot [20] registered [ 0.341180] acpiphp: Slot [21] registered [ 0.343186] acpiphp: Slot [22] registered [ 0.345158] acpiphp: Slot [23] registered [ 0.347299] acpiphp: Slot [24] registered [ 0.349141] acpiphp: Slot [25] registered [ 0.351112] acpiphp: Slot [26] registered [ 0.352130] acpiphp: Slot [27] registered [ 0.354152] acpiphp: Slot [28] registered [ 0.356174] acpiphp: Slot [29] registered [ 0.357218] acpiphp: Slot [30] registered [ 0.359132] acpiphp: Slot [31] registered [ 0.361115] PCI host bridge to bus 0000:00 [ 0.363026] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.365031] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.368022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.370030] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.373051] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.375042] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.378264] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.381190] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.385528] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.393559] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.402753] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.408046] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.413028] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.418028] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.423793] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.426941] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.430047] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.433934] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.438019] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.449666] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.454037] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.460178] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.469023] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.479018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.506037] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.519353] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.529018] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.538015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.566017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.581445] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.584446] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.588457] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.590392] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.593241] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.598185] iommu: Default domain type: Passthrough [ 0.599515] SCSI subsystem initialized [ 0.601145] ACPI: bus type USB registered [ 0.602179] usbcore: registered new interface driver usbfs [ 0.604108] usbcore: registered new interface driver hub [ 0.605113] usbcore: registered new device driver usb [ 0.607275] pps_core: LinuxPPS API ver. 1 registered [ 0.609017] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.612093] PTP clock support registered [ 0.615156] EDAC MC: Ver: 3.0.0 [ 0.617151] PCI: Using ACPI for IRQ routing [ 0.618775] NetLabel: Initializing [ 0.619012] NetLabel: domain hash size = 128 [ 0.620017] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.621149] NetLabel: unlabeled traffic allowed by default [ 0.623191] vgaarb: loaded [ 0.624335] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.627017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.632432] clocksource: Switched to clocksource kvm-clock [ 0.749245] VFS: Disk quotas dquot_6.6.0 [ 0.751538] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.754709] *** VALIDATE ramfs *** [ 0.756357] *** VALIDATE hugetlbfs *** [ 0.758353] pnp: PnP ACPI init [ 0.761808] pnp: PnP ACPI: found 6 devices [ 0.780675] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.784991] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.787739] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.790518] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.793498] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.796463] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.799870] NET: Registered protocol family 2 [ 0.802324] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.807562] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.811907] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.818000] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.822151] TCP: Hash tables configured (established 65536 bind 65536) [ 0.825680] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.829500] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.833029] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.836722] NET: Registered protocol family 1 [ 0.841271] RPC: Registered named UNIX socket transport module. [ 0.843835] RPC: Registered udp transport module. [ 0.845381] RPC: Registered tcp transport module. [ 0.846823] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.849526] NET: Registered protocol family 44 [ 0.851592] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.854219] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.857303] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.859926] PCI: CLS 0 bytes, default 64 [ 0.862394] Unpacking initramfs... [ 2.328453] debug: unmapping init [mem 0xffff97ef3cc64000-0xffff97ef3ffcffff] [ 2.335707] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.340083] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.344917] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.850166] Initialise system trusted keyrings [ 2.852284] Key type blacklist registered [ 2.855140] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.865797] zbud: loaded [ 2.870203] *** VALIDATE nfs *** [ 2.871755] *** VALIDATE nfs4 *** [ 2.873675] pstore: using deflate compression [ 2.876915] Platform Keyring initialized [ 2.983469] NET: Registered protocol family 38 [ 2.985604] Key type asymmetric registered [ 2.987073] Asymmetric key parser 'x509' registered [ 2.989147] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.992331] io scheduler mq-deadline registered [ 2.994311] io scheduler kyber registered [ 2.996321] io scheduler bfq registered [ 2.998671] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.002213] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.005450] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.008785] ACPI: Power Button [PWRF] [ 3.014747] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.022806] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.035303] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.064095] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.094131] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.099907] Non-volatile memory driver v1.3 [ 3.102121] Linux agpgart interface v0.103 [ 3.133553] virtio_blk virtio1: [vda] 136464 512-byte logical blocks (69.9 MB/66.6 MiB) [ 3.137463] vda: detected capacity change from 0 to 69869568 [ 3.152847] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.156066] vdb: detected capacity change from 0 to 1073741824 [ 3.163286] libphy: Fixed MDIO Bus: probed [ 3.169232] usbcore: registered new interface driver usbserial_generic [ 3.171988] usbserial: USB Serial support registered for generic [ 3.175044] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.179749] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.181893] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.184808] mousedev: PS/2 mouse device common for all mice [ 3.188467] rtc_cmos 00:05: RTC can wake from S4 [ 3.191977] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.192255] rtc_cmos 00:05: registered as rtc0 [ 3.198346] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.202825] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.203031] intel_pstate: CPU model not supported [ 3.209073] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.213397] hid: raw HID events driver (C) Jiri Kosina [ 3.215982] usbcore: registered new interface driver usbhid [ 3.218394] usbhid: USB HID core driver [ 3.220410] drop_monitor: Initializing network drop monitor service [ 3.223291] Initializing XFRM netlink socket [ 3.225633] NET: Registered protocol family 10 [ 3.228910] Segment Routing with IPv6 [ 3.230397] NET: Registered protocol family 17 [ 3.232548] mpls_gso: MPLS GSO support [ 3.237373] RAS: Correctable Errors collector initialized. [ 3.239151] AVX version of gcm_enc/dec engaged. [ 3.240743] AES CTR mode by8 optimization enabled [ 3.322089] sched_clock: Marking stable (3322033471, 0)->(4260848181, -938814710) [ 3.325423] registered taskstats version 1 [ 3.327175] Loading compiled-in X.509 certificates [ 3.329258] zswap: loaded using pool lzo/zbud [ 3.356993] Key type big_key registered [ 3.369817] Key type encrypted registered [ 3.371793] ima: No TPM chip found, activating TPM-bypass! [ 3.374255] ima: Allocated hash algorithm: sha1 [ 3.376338] ima: No architecture policies found [ 3.378404] evm: Initialising EVM extended attributes: [ 3.380501] evm: security.selinux [ 3.381922] evm: security.ima [ 3.383198] evm: security.capability [ 3.384805] evm: HMAC attrs: 0x1 [ 3.387372] rtc_cmos 00:05: setting system clock to 2026-06-12 02:03:18 UTC (1781229798) [ 3.394390] debug: unmapping init [mem 0xffffffffbcc03000-0xffffffffbcdfffff] [ 3.397389] debug: unmapping init [mem 0xffffffffbb982000-0xffffffffbbc58fff] [ 3.407278] Write protecting the kernel read-only data: 28672k [ 3.410741] debug: unmapping init [mem 0xffffffffba003000-0xffffffffba1fffff] [ 3.413893] debug: unmapping init [mem 0xffffffffba914000-0xffffffffba9fffff] [ 3.446062] 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.457770] systemd[1]: Detected virtualization kvm. [ 3.459904] systemd[1]: Detected architecture x86-64. [ 3.461909] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.489219] systemd[1]: No hostname configured. [ 3.491332] systemd[1]: Set hostname to . [ 3.493422] random: systemd: uninitialized urandom read (16 bytes read) [ 3.495945] systemd[1]: Initializing machine ID from random generator. [ 3.538100] random: ln: uninitialized urandom read (6 bytes read) [ 3.655330] random: systemd: uninitialized urandom read (16 bytes read) [ 3.660540] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.667647] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.672505] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Slices. [ OK ] Reached target Local File Systems. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Journal Service... Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... Starting Create Volatile Files and Directories... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.343513] device-mapper: uevent: version 1.0.3 [ 4.345723] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.112361] virtio_net virtio0 ens2: renamed from eth0 [ 5.182280] scsi host0: ata_piix [ 5.196897] scsi host1: ata_piix [ 5.221206] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.223948] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.531071] dracut-initqueue[589]: RTNETLINK answers: File exists [ 9.890648] random: crng init done [ 9.892835] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.280699] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.396104] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.659134] SELinux: Disabled at runtime. [ 11.720086] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.729555] systemd[1]: Detected virtualization kvm. [ 11.731833] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.197828] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.201695] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.206318] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.210906] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.215387] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.229458] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.238080] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. Mounting Kernel Debug File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on RPCbind Server Activation Socket. Mounting POSIX Message Queue File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target RPC Port Mapper. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting Remount Root and Kernel File Systems... Mounting Huge Pages File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ 12.339757] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Apply Kernel Variables... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target Paths. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ 12.680606] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /mnt. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.991786] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.044220] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.113462] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.141254] EDAC sbridge: Ver: 1.1.2 [ 14.267949] Key type dns_resolver registered [ 14.576066] NFS: Registering the id_resolver key type [ 14.579829] Key type id_resolver registered [ 14.581880] Key type id_legacy registered [ 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 RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ 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 OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg355-client login: [ 42.477495] libcfs: loading out-of-tree module taints kernel. [ 42.536227] Key type ._llcrypt registered [ 42.538184] Key type .llcrypt registered [ 42.857238] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 42.864348] alg: No test for adler32 (adler32-zlib) [ 43.941425] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 44.303878] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 45.943219] Key type lgssc registered [ 46.753662] Lustre: Echo OBD driver; http://www.lustre.org/ [ 202.902137] Lustre: Mounted lustre-client [ 207.399847] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 220.540570] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing check_logdir /tmp/testlogs/ [ 226.428175] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing yml_node [ 228.839281] Lustre: lustre-OST0000-osc-ffff97ef83e11800: disconnect after 23s idle [ 229.269055] hrtimer: interrupt took 6044317 ns [ 231.670948] Lustre: DEBUG MARKER: Client: 2.17.53.61 [ 234.299110] Lustre: DEBUG MARKER: MDS: 2.17.53.61 [ 236.436039] Lustre: DEBUG MARKER: OSS: 2.17.53.61 [ 237.812307] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Jun 11 22:07:11 EDT 2026 [ 253.349630] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 254.779638] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 257.323583] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 267.660441] Lustre: Unmounted lustre-client [ 380.264793] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 384.529532] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 406.874284] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 442.890852] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 22:10:36 (1781230236) [ 445.201149] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 447.170405] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 22:10:40 (1781230240) [ 477.213899] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 481.582429] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 500.309562] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 500.959343] Lustre: Mounted lustre-client [ 505.750136] Lustre: Unmounted lustre-client [ 531.863754] Key type lgssc unregistered [ 532.202939] LNet: 14831:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 532.218882] LNetError: 14831:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 532.237527] LNet: Removed LNI 192.168.203.55@tcp [ 532.849404] Key type .llcrypt unregistered [ 532.854716] Key type ._llcrypt unregistered [ 549.297738] Key type ._llcrypt registered [ 549.300532] Key type .llcrypt registered [ 550.987459] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 22:12:24 (1781230344) [ 552.815830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 552.844989] alg: No test for adler32 (adler32-zlib) [ 553.926873] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 554.209404] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 555.928506] Key type lgssc registered [ 557.350596] Lustre: Echo OBD driver; http://www.lustre.org/ [ 632.915648] Lustre: Mounted lustre-client [ 722.643719] Lustre: Unmounted lustre-client [ 757.187596] Key type lgssc unregistered [ 757.541687] LNet: 18650:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 757.549452] LNetError: 18650:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 758.567090] LNet: Removed LNI 192.168.203.55@tcp [ 759.544647] Key type .llcrypt unregistered [ 759.547840] Key type ._llcrypt unregistered [ 769.290346] Key type ._llcrypt registered [ 769.292588] Key type .llcrypt registered [ 771.091506] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 22:16:04 (1781230564) [ 774.081558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 774.156661] alg: No test for adler32 (adler32-zlib) [ 775.219262] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 775.505566] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 777.256572] Key type lgssc registered [ 778.518542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 829.295273] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 833.062292] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 853.488904] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 856.272957] Lustre: Mounted lustre-client [ 867.296223] Lustre: Unmounted lustre-client [ 894.614440] Key type lgssc unregistered [ 894.859502] LNet: 22164:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 894.872551] LNetError: 22164:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 894.892155] LNet: Removed LNI 192.168.203.55@tcp [ 895.484767] Key type .llcrypt unregistered [ 895.487270] Key type ._llcrypt unregistered [ 906.331403] Key type ._llcrypt registered [ 906.333905] Key type .llcrypt registered [ 907.658501] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 22:18:21 (1781230701) [ 910.077789] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 910.167521] alg: No test for adler32 (adler32-zlib) [ 911.271449] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 911.452532] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 913.151190] Key type lgssc registered [ 914.363436] Lustre: Echo OBD driver; http://www.lustre.org/ [ 962.263716] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 966.985402] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 986.279032] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 987.821687] Lustre: Mounted lustre-client [ 993.092388] Lustre: Unmounted lustre-client [ 1029.470269] Key type lgssc unregistered [ 1029.725375] LNet: 25268:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1029.738438] LNetError: 25268:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1029.757934] LNet: Removed LNI 192.168.203.55@tcp [ 1030.595192] Key type .llcrypt unregistered [ 1030.600042] Key type ._llcrypt unregistered [ 1046.231614] Key type ._llcrypt registered [ 1046.234984] Key type .llcrypt registered [ 1047.594478] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 22:20:41 (1781230841) [ 1050.300662] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1050.378349] alg: No test for adler32 (adler32-zlib) [ 1051.504870] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 1051.786301] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 1053.495129] Key type lgssc registered [ 1054.716867] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1103.017356] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1107.074101] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1127.423537] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1130.073690] Lustre: Mounted lustre-client [ 1135.084232] Lustre: lustre-MDT0000-mdc-ffff97ef88743000: Connection to lustre-MDT0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1150.444765] Lustre: lustre-MDT0001-mdc-ffff97ef88743000: Connection to lustre-MDT0001 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1154.469351] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1161.695445] Lustre: 26226:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781230940/real 1781230940] req@000000000f6c7926 x1867755915581440/t0(0) o400->MGC192.168.203.155@tcp@192.168.203.155@tcp:26/25 lens 224/224 e 0 to 1 dl 1781230956 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1161.734647] LustreError: MGC192.168.203.155@tcp: Connection to MGS (at 192.168.203.155@tcp) was lost; in progress operations using this service will fail [ 1164.854353] Lustre: Unmounted lustre-client [ 1185.599908] Key type lgssc unregistered [ 1185.910291] LNet: 28838:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1185.916557] LNetError: 28838:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1185.935295] LNet: Removed LNI 192.168.203.55@tcp [ 1186.644581] Key type .llcrypt unregistered [ 1186.648363] Key type ._llcrypt unregistered [ 1204.098578] Key type ._llcrypt registered [ 1204.100637] Key type .llcrypt registered [ 1205.630919] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 22:23:19 (1781230999) [ 1207.912451] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1207.960068] alg: No test for adler32 (adler32-zlib) [ 1209.084447] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 1209.311703] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 1211.019913] Key type lgssc registered [ 1212.335879] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1264.563252] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1268.619966] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1290.862792] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1293.708111] Lustre: Mounted lustre-client [ 1298.939811] Lustre: lustre-OST0000-osc-ffff97efa05ae000: Connection to lustre-OST0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1315.203945] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1325.677615] Lustre: Unmounted lustre-client [ 1363.449686] Key type lgssc unregistered [ 1363.903978] LNet: 32363:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1363.927667] LNetError: 32363:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1363.955441] LNet: Removed LNI 192.168.203.55@tcp [ 1364.660712] Key type .llcrypt unregistered [ 1364.665644] Key type ._llcrypt unregistered [ 1381.961476] Key type ._llcrypt registered [ 1381.964427] Key type .llcrypt registered [ 1383.877654] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 22:26:16 (1781231176) [ 1386.968190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1387.007749] alg: No test for adler32 (adler32-zlib) [ 1388.289722] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 1388.612318] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 1390.359228] Key type lgssc registered [ 1391.503833] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1444.655759] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1450.333390] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1472.315981] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1474.149977] Lustre: Mounted lustre-client [ 1480.924863] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1484.483819] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1495.884811] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-06-1122h28m09s [ 1498.031558] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1504.749311] Lustre: lustre-MDT0000-mdc-ffff97ef842ef800: Connection to lustre-MDT0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1510.747695] LustreError: 36061:0:(lmv_obd.c:1450:lmv_statfs()) lustre-MDT0000-mdc-ffff97ef842ef800: can't stat MDS #0: rc = -19 [ 1512.471267] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1523.181779] LustreError: MGC192.168.203.155@tcp: Connection to MGS (at 192.168.203.155@tcp) was lost; in progress operations using this service will fail [ 1523.237328] Lustre: Evicted from MGS (at 192.168.203.155@tcp) after server handle changed from 0xad741e2c138de4d0 to 0xad741e2c138de79a [ 1523.275170] Lustre: MGC192.168.203.155@tcp: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 1524.274964] LustreError: lustre-MDT0000-mdc-ffff97ef842ef800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1524.302306] Lustre: lustre-MDT0000-mdc-ffff97ef842ef800: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 1531.661400] Lustre: DEBUG MARKER: Wait for df (36061) ... [ 1534.150915] Lustre: DEBUG MARKER: done [ 1538.163186] Lustre: Unmounted lustre-client [ 1568.961576] Key type lgssc unregistered [ 1569.427920] LNet: 37083:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1569.438551] LNetError: 37083:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1569.483629] LNet: Removed LNI 192.168.203.55@tcp [ 1570.404272] Key type .llcrypt unregistered [ 1570.408938] Key type ._llcrypt unregistered [ 1605.446551] Key type ._llcrypt registered [ 1605.449857] Key type .llcrypt registered [ 1606.765950] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 22:30:00 (1781231400) [ 1609.816025] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1609.852309] alg: No test for adler32 (adler32-zlib) [ 1611.083773] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 1611.292479] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 1612.991197] Key type lgssc registered [ 1614.095567] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1667.172254] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1671.394968] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1693.119435] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1694.773840] Lustre: Mounted lustre-client [ 1700.121876] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-06-1122h31m33s [ 1701.799023] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1709.372779] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1712.383536] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1732.575586] Lustre: 41138:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781231507/real 1781231507] req@00000000143b32fa x1867756502784640/t0(0) o101->lustre-MDT0000-mdc-ffff97ef86293000@192.168.203.155@tcp:12/10 lens 576/1152 e 0 to 1 dl 1781231527 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1732.618927] Lustre: lustre-MDT0000-mdc-ffff97ef86293000: Connection to lustre-MDT0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1732.667095] Lustre: lustre-MDT0000-mdc-ffff97ef86293000: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 1754.594588] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1758.060726] Lustre: DEBUG MARKER: done [ 1761.998963] Lustre: Unmounted lustre-client [ 1790.194608] Key type lgssc unregistered [ 1790.633720] LNet: 42023:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1790.644214] LNetError: 42023:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1790.668478] LNet: Removed LNI 192.168.203.55@tcp [ 1791.502095] Key type .llcrypt unregistered [ 1791.504978] Key type ._llcrypt unregistered [ 1826.215914] Key type ._llcrypt registered [ 1826.218625] Key type .llcrypt registered [ 1827.615194] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 22:33:41 (1781231621) [ 1829.232059] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1830.765336] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 22:33:44 (1781231624) [ 1834.502495] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1834.534272] alg: No test for adler32 (adler32-zlib) [ 1835.630948] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 1835.839678] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 1837.567218] Key type lgssc registered [ 1838.872705] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1868.724454] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 22:34:21 (1781231661) [ 1900.588302] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1904.689654] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1926.409650] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1928.148080] Lustre: Mounted lustre-client [ 1931.867949] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1936.662573] Lustre: Unmounted lustre-client [ 1955.169303] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2002.426230] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2007.648924] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2008.607325] Lustre: Mounted lustre-client [ 2013.167188] Lustre: Unmounted lustre-client [ 2075.368181] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2079.059917] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2079.947820] Lustre: Mounted lustre-client [ 2083.854908] Lustre: Unmounted lustre-client [ 2100.379861] Lustre: DEBUG MARKER: files compared the same [ 2122.677311] Key type lgssc unregistered [ 2122.999387] LNet: 49440:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2123.011826] LNetError: 49440:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2123.027673] LNet: Removed LNI 192.168.203.55@tcp [ 2123.896643] Key type .llcrypt unregistered [ 2123.900324] Key type ._llcrypt unregistered [ 2140.583233] Key type ._llcrypt registered [ 2140.585137] Key type .llcrypt registered [ 2141.596340] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 22:38:55 (1781231935) [ 2144.225994] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2144.293201] alg: No test for adler32 (adler32-zlib) [ 2145.332864] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 2145.542666] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 2147.191927] Key type lgssc registered [ 2148.406495] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2195.345989] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2198.844419] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2220.131101] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2221.724300] Lustre: Mounted lustre-client [ 2226.608207] Lustre: Unmounted lustre-client [ 2263.470206] Key type lgssc unregistered [ 2263.839258] LNet: 52934:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2263.853876] LNetError: 52934:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2263.883795] LNet: Removed LNI 192.168.203.55@tcp [ 2264.727512] Key type .llcrypt unregistered [ 2264.738153] Key type ._llcrypt unregistered [ 2280.829035] Key type ._llcrypt registered [ 2280.833323] Key type .llcrypt registered [ 2281.899118] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 22:41:15 (1781232075) [ 2283.892536] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2284.001151] alg: No test for adler32 (adler32-zlib) [ 2285.054417] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 2285.269592] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 2286.922539] Key type lgssc registered [ 2288.126789] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2370.480384] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2406.399404] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2410.543768] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2448.377648] Key type lgssc unregistered [ 2448.633410] LNet: 56332:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2448.644384] LNetError: 56332:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2448.660640] LNet: Removed LNI 192.168.203.55@tcp [ 2449.476532] Key type .llcrypt unregistered [ 2449.479667] Key type ._llcrypt unregistered [ 2465.946310] Key type ._llcrypt registered [ 2465.950167] Key type .llcrypt registered [ 2467.249737] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 22:44:20 (1781232260) [ 2470.745684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2470.774656] alg: No test for adler32 (adler32-zlib) [ 2471.773372] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 2472.058822] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 2473.743310] Key type lgssc registered [ 2475.572172] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2558.311253] Lustre: Mounted lustre-client [ 2566.248283] Lustre: Unmounted lustre-client [ 2605.484405] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 22:46:39 (1781232399) [ 2738.426289] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2754.205978] Lustre: Mounted lustre-client [ 2762.130549] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2762.142405] LustreError: 62774:0:(file.c:252:ll_close_inode_openhandle()) lustre-clilmv-ffff97ef927fd000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2762.192916] Lustre: 57317:0:(llite_lib.c:4198:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.155@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2772.507792] Lustre: Unmounted lustre-client [ 2819.892547] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 22:50:13 (1781232613) [ 2833.480233] Key type lgssc unregistered [ 2833.803294] LNet: 63986:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2833.814342] LNetError: 63986:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2833.859429] LNet: Removed LNI 192.168.203.55@tcp [ 2834.736641] Key type .llcrypt unregistered [ 2834.741457] Key type ._llcrypt unregistered [ 2847.690137] Key type ._llcrypt registered [ 2847.693646] Key type .llcrypt registered [ 2848.341899] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2848.384919] alg: No test for adler32 (adler32-zlib) [ 2849.672251] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 2849.984293] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 2851.695872] Key type lgssc registered [ 2853.030687] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2904.054342] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2908.763537] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2930.193467] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2932.979561] Lustre: Mounted lustre-client [ 2937.556315] Lustre: Unmounted lustre-client [ 2966.742383] Key type lgssc unregistered [ 2967.033558] LNet: 66911:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2967.039388] LNetError: 66911:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2967.061846] LNet: Removed LNI 192.168.203.55@tcp [ 2967.808820] Key type .llcrypt unregistered [ 2967.811908] Key type ._llcrypt unregistered [ 2978.576088] Key type ._llcrypt registered [ 2978.581513] Key type .llcrypt registered [ 2979.088930] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2979.116797] alg: No test for adler32 (adler32-zlib) [ 2980.304184] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 2980.592478] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 2982.335241] Key type lgssc registered [ 2983.743721] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3093.166123] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3097.023964] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3117.874600] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3120.124934] Lustre: Mounted lustre-client [ 3123.753539] Lustre: Unmounted lustre-client [ 3153.377673] Key type lgssc unregistered [ 3153.660266] LNet: 70252:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3153.670148] LNetError: 70252:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3153.700955] LNet: Removed LNI 192.168.203.55@tcp [ 3154.536250] Key type .llcrypt unregistered [ 3154.539324] Key type ._llcrypt unregistered [ 3171.434043] Key type ._llcrypt registered [ 3171.439376] Key type .llcrypt registered [ 3173.247152] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 22:56:06 (1781232966) [ 3176.225419] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3176.283264] alg: No test for adler32 (adler32-zlib) [ 3177.498645] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 3177.776089] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 3179.519651] Key type lgssc registered [ 3180.621591] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3230.869046] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3235.242850] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3255.568196] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3258.463226] Lustre: Mounted lustre-client [ 3264.642021] Lustre: Unmounted lustre-client [ 3265.186612] Lustre: Mounted lustre-client [ 3269.471239] Lustre: Unmounted lustre-client [ 3298.219890] Key type lgssc unregistered [ 3298.544732] LNet: 73854:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3298.561688] LNetError: 73854:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3298.597253] LNet: Removed LNI 192.168.203.55@tcp [ 3299.447472] Key type .llcrypt unregistered [ 3299.452144] Key type ._llcrypt unregistered [ 3312.798562] Key type ._llcrypt registered [ 3312.801683] Key type .llcrypt registered [ 3313.454631] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3313.478701] alg: No test for adler32 (adler32-zlib) [ 3314.712271] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 3315.052698] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 3316.711194] Key type lgssc registered [ 3318.268539] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3372.719703] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3376.976685] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3396.166181] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3398.267215] Lustre: Mounted lustre-client [ 3403.147492] Lustre: Unmounted lustre-client [ 3440.084809] Key type lgssc unregistered [ 3440.397899] LNet: 76802:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3440.411074] LNetError: 76802:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3440.431248] LNet: Removed LNI 192.168.203.55@tcp [ 3441.190913] Key type .llcrypt unregistered [ 3441.194345] Key type ._llcrypt unregistered [ 3455.154615] Key type ._llcrypt registered [ 3455.156842] Key type .llcrypt registered [ 3456.425286] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 23:00:50 (1781233250) [ 3459.766606] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3459.782217] alg: No test for adler32 (adler32-zlib) [ 3460.889449] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 3461.129146] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 3462.791229] Key type lgssc registered [ 3464.072535] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3514.836602] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3519.382838] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3541.294352] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3543.288166] Lustre: Mounted lustre-client [ 3557.426518] Lustre: lustre: nosquash_nids is cleared [ 3557.458586] Lustre: lustre: root_squash is set to 500:500 [ 3575.841196] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.55@tcp 192.168.0.[2,10]@tcp [ 3582.505664] Lustre: lustre: nosquash_nids set to 192.168.203.55/24@tcp [ 3593.141830] Lustre: Unmounted lustre-client [ 3621.267546] Key type lgssc unregistered [ 3621.676125] LNet: 80717:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3621.694601] LNetError: 80717:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3621.732441] LNet: Removed LNI 192.168.203.55@tcp [ 3622.683898] Key type .llcrypt unregistered [ 3622.685720] Key type ._llcrypt unregistered [ 3639.116623] Key type ._llcrypt registered [ 3639.118718] Key type .llcrypt registered [ 3640.561376] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 23:03:54 (1781233434) [ 3643.630478] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3645.517655] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 23:03:58 (1781233438) [ 3648.339621] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3648.361164] alg: No test for adler32 (adler32-zlib) [ 3649.399963] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 3649.613941] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 3651.295152] Key type lgssc registered [ 3652.485907] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3700.611247] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3704.460984] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3723.151854] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3725.104929] Lustre: lustre: root_squash is set to 500:500 [ 3725.154818] Lustre: lustre: nosquash_nids set to 192.168.203.55/24@tcp [ 3726.193443] Lustre: Mounted lustre-client [ 3732.166200] Lustre: Unmounted lustre-client [ 3760.116576] Key type lgssc unregistered [ 3760.339107] LNet: 84587:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3760.355598] LNetError: 84587:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3760.373198] LNet: Removed LNI 192.168.203.55@tcp [ 3761.040650] Key type .llcrypt unregistered [ 3761.043071] Key type ._llcrypt unregistered [ 3777.553240] Key type ._llcrypt registered [ 3777.557800] Key type .llcrypt registered [ 3779.091745] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3780.880088] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 23:06:14 (1781233574) [ 3800.885634] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3800.927639] alg: No test for adler32 (adler32-zlib) [ 3802.067097] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 3802.335230] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 3804.064079] Key type lgssc registered [ 3805.600633] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3889.855245] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3894.015580] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3915.825906] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3986.041689] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3990.208778] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4010.769749] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4023.974318] Lustre: Mounted lustre-client [ 4054.468827] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff97ef90037000.ost_server_uuid 50 [ 4056.459156] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff97ef90037000.ost_server_uuid in IDLE state after 0 sec [ 4057.099721] Lustre: Mounted lustre-client [ 4061.962943] Lustre: Unmounted lustre-client [ 4064.982496] Lustre: Unmounted lustre-client [ 4113.139234] Key type lgssc unregistered [ 4113.557351] LNet: 92064:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4113.572074] LNetError: 92064:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4113.603658] LNet: Removed LNI 192.168.203.55@tcp [ 4114.258251] Key type .llcrypt unregistered [ 4114.261698] Key type ._llcrypt unregistered [ 4144.087414] Key type ._llcrypt registered [ 4144.090498] Key type .llcrypt registered [ 4145.451513] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 23:12:19 (1781233939) [ 4164.171853] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4164.187080] alg: No test for adler32 (adler32-zlib) [ 4165.293697] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 4165.681186] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 4167.439425] Key type lgssc registered [ 4168.856042] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4248.154947] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4252.607512] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4275.036636] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4276.928282] Lustre: Mounted lustre-client [ 4298.721076] Lustre: 93427:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781234077/real 1781234077] req@000000003ac90dfb x1867759180846208/t0(0) o400->lustre-OST0000-osc-ffff97ef854c1800@192.168.203.155@tcp:28/4 lens 224/224 e 0 to 1 dl 1781234093 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4298.765185] Lustre: lustre-OST0000-osc-ffff97ef854c1800: Connection to lustre-OST0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4303.776398] Lustre: 93427:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781234082/real 1781234082] req@000000008dee1350 x1867759180846720/t0(0) o400->lustre-OST0000-osc-ffff97ef854c1800@192.168.203.155@tcp:28/4 lens 224/224 e 0 to 1 dl 1781234098 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4307.935412] Lustre: 93427:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781234087/real 1781234087] req@00000000091d1fa5 x1867759180847232/t0(0) o400->lustre-OST0000-osc-ffff97ef854c1800@192.168.203.155@tcp:28/4 lens 224/224 e 0 to 1 dl 1781234103 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4310.029831] Lustre: lustre-OST0000-osc-ffff97ef854c1800: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 4313.096196] Lustre: 93427:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781234092/real 1781234092] req@00000000f999da50 x1867759180847744/t0(0) o400->lustre-OST0000-osc-ffff97ef854c1800@192.168.203.155@tcp:28/4 lens 224/224 e 0 to 1 dl 1781234108 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4318.188660] Lustre: lustre-MDT0000-mdc-ffff97ef854c1800: Connection to lustre-MDT0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4333.562051] LustreError: MGC192.168.203.155@tcp: Connection to MGS (at 192.168.203.155@tcp) was lost; in progress operations using this service will fail [ 4333.614501] Lustre: Evicted from MGS (at 192.168.203.155@tcp) after server handle changed from 0x2a5e46038433445d to 0x2a5e460384334608 [ 4333.639648] Lustre: MGC192.168.203.155@tcp: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 4339.184978] Lustre: lustre-MDT0000-mdc-ffff97ef854c1800: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 4344.599760] Lustre: Unmounted lustre-client [ 4381.965472] Key type lgssc unregistered [ 4382.252895] LNet: 97639:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4382.267178] LNetError: 97639:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4382.290645] LNet: Removed LNI 192.168.203.55@tcp [ 4383.107751] Key type .llcrypt unregistered [ 4383.110898] Key type ._llcrypt unregistered [ 4399.710902] Key type ._llcrypt registered [ 4399.715561] Key type .llcrypt registered [ 4401.447773] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 23:16:34 (1781234194) [ 4416.676069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4416.705320] alg: No test for adler32 (adler32-zlib) [ 4417.913297] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 4418.136726] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 4419.833084] Key type lgssc registered [ 4421.191528] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4461.153966] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4573.782646] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4578.367779] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4601.891336] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4604.756412] Lustre: Mounted lustre-client [ 4607.169486] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4608.902697] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 23:20:02 (1781234402) [ 4876.632262] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4886.967908] Lustre: Unmounted lustre-client [ 5004.893364] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5008.764597] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5033.412090] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5079.825520] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 23:27:53 (1781234873) [ 5172.747524] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5176.970537] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5198.238598] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5199.034098] Lustre: Mounted lustre-client [ 5203.054341] Lustre: Unmounted lustre-client [ 5244.043882] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 23:30:37 (1781235037) [ 5335.921780] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5340.363867] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5360.544529] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5361.108580] Lustre: Mounted lustre-client [ 5365.750874] Lustre: Unmounted lustre-client [ 5391.307573] Key type lgssc unregistered [ 5391.537086] LNet: 120292:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5391.546533] LNetError: 120292:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5391.564954] LNet: Removed LNI 192.168.203.55@tcp [ 5392.223323] Key type .llcrypt unregistered [ 5392.226265] Key type ._llcrypt unregistered [ 5405.160884] Key type ._llcrypt registered [ 5405.163693] Key type .llcrypt registered [ 5406.248098] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 23:33:20 (1781235200) [ 5408.536153] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5408.566123] alg: No test for adler32 (adler32-zlib) [ 5409.585502] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 5409.747810] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 5411.383630] Key type lgssc registered [ 5412.579475] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5454.378861] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5457.633411] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5475.057616] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5482.034410] Lustre: Mounted lustre-client [ 5495.133806] Lustre: Unmounted lustre-client [ 5524.885088] Key type lgssc unregistered [ 5525.266276] LNet: 123795:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5525.284578] LNetError: 123795:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5525.301615] LNet: Removed LNI 192.168.203.55@tcp [ 5526.231068] Key type .llcrypt unregistered [ 5526.235514] Key type ._llcrypt unregistered [ 5543.316134] Key type ._llcrypt registered [ 5543.319350] Key type .llcrypt registered [ 5545.179778] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 23:35:38 (1781235338) [ 5547.569067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5547.596262] alg: No test for adler32 (adler32-zlib) [ 5548.810544] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 5549.053772] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 5550.711167] Key type lgssc registered [ 5552.108211] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5596.793187] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5600.351909] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5619.029206] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5621.685577] Lustre: Mounted lustre-client [ 5626.855304] Lustre: lustre-OST0000-osc-ffff97ef900bf000: Connection to lustre-OST0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5637.481285] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff97ef900bf000.ost_server_uuid 50 [ 5639.068376] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5650.420896] Lustre: Unmounted lustre-client [ 5671.754034] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 23:37:45 (1781235465) [ 5703.104302] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5707.495455] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5724.805502] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5746.802949] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5747.417905] Lustre: Mounted lustre-client [ 5752.832786] Lustre: lustre-OST0000-osc-ffff97ef89f89000: Connection to lustre-OST0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5778.519028] Lustre: Unmounted lustre-client [ 5828.586745] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 23:40:21 (1781235621) [ 5862.687331] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5866.376791] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5885.614950] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5908.028643] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5908.749348] Lustre: Mounted lustre-client [ 5914.095577] Lustre: lustre-OST0000-osc-ffff97ef900bf000: Connection to lustre-OST0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5930.618675] Lustre: Unmounted lustre-client [ 5985.322750] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 23:42:58 (1781235778) [ 6079.966945] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6083.594891] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6103.925432] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6173.281408] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6178.001929] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6201.330549] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6231.142434] Lustre: Mounted lustre-client [ 6270.842305] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6278.249417] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 5 sec [ 6280.223650] Lustre: Unmounted lustre-client [ 6315.489957] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 23:48:29 (1781236109) [ 6347.513986] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6351.219288] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6371.597922] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6404.163949] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6435.956743] Lustre: Mounted lustre-client [ 6478.196832] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6479.588815] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6481.900545] Lustre: lustre-OST0001-osc-ffff97ef9114d800: Connection to lustre-OST0001 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6499.410237] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6509.720223] Lustre: Unmounted lustre-client [ 6575.172143] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 23:52:48 (1781236368) [ 6613.881987] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6617.906742] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6640.716727] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6641.514628] Lustre: Mounted lustre-client [ 6665.628089] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6667.176909] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6681.907177] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff97ef854c4000.ost_server_uuid 50 [ 6683.652475] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff97ef854c4000.ost_server_uuid in IDLE state after 0 sec [ 6687.115808] Lustre: Unmounted lustre-client [ 6687.411664] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6687.417887] Lustre: Skipped 3 previous similar messages [ 6687.515447] Lustre: Mounted lustre-client [ 6688.712536] LustreError: 146434:0:(sec.c:413:import_sec_validate_get()) import 0000000005c42d24 (NEW) with no sec [ 6743.177712] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 23:55:37 (1781236537) [ 6779.588126] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6783.894898] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6804.298432] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6825.254698] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6825.885203] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6826.030184] Lustre: Mounted lustre-client [ 6840.843736] Lustre: Unmounted lustre-client [ 6840.849098] Lustre: Skipped 1 previous similar message [ 6880.369808] Key type lgssc unregistered [ 6880.652825] LNet: 149736:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6880.660722] LNetError: 149736:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6880.679804] LNet: Removed LNI 192.168.203.55@tcp [ 6881.437180] Key type .llcrypt unregistered [ 6881.443069] Key type ._llcrypt unregistered [ 6899.437994] Key type ._llcrypt registered [ 6899.443413] Key type .llcrypt registered [ 6900.909952] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 23:58:14 (1781236694) [ 6902.224158] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6902.260148] alg: No test for adler32 (adler32-zlib) [ 6903.409469] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 6903.732803] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 6905.423163] Key type lgssc registered [ 6907.243967] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6961.851147] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6965.954610] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6992.516280] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7013.891804] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7015.418591] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7016.582259] Lustre: Mounted lustre-client [ 7019.402569] LustreError: lustre-MDT0001-mdc-ffff97ef9155c800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7019.415982] Lustre: lustre-MDT0001-mdc-ffff97ef9155c800: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 7024.706868] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7026.654238] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7048.237986] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7052.862497] Lustre: Unmounted lustre-client [ 7086.154643] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 00:01:19 (1781236879) [ 7200.283086] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7203.960987] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7227.176405] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7227.896423] Lustre: Mounted lustre-client [ 7253.677968] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7255.353187] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7258.096712] Lustre: lustre-OST0001-osc-ffff97ef88741800: Connection to lustre-OST0001 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7267.920293] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7278.245718] Lustre: Unmounted lustre-client [ 7322.232357] Key type lgssc unregistered [ 7322.573758] LNet: 159090:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7322.584816] LNetError: 159090:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7322.609579] LNet: Removed LNI 192.168.203.55@tcp [ 7323.487203] Key type .llcrypt unregistered [ 7323.493937] Key type ._llcrypt unregistered [ 7357.343408] Key type ._llcrypt registered [ 7357.345874] Key type .llcrypt registered [ 7358.583312] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 00:05:52 (1781237152) [ 7360.998966] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7361.073211] alg: No test for adler32 (adler32-zlib) [ 7362.104788] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 7362.321342] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 7363.967315] Key type lgssc registered [ 7365.266544] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7418.011792] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7422.293581] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7443.396194] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7446.207711] Lustre: Mounted lustre-client [ 7493.565275] Lustre: Unmounted lustre-client [ 7532.074038] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7532.890971] Lustre: Mounted lustre-client [ 7569.109327] Lustre: Unmounted lustre-client [ 7598.927615] Key type lgssc unregistered [ 7599.150177] LNet: 163419:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7599.156949] LNetError: 163419:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7599.174392] LNet: Removed LNI 192.168.203.55@tcp [ 7599.865801] Key type .llcrypt unregistered [ 7599.869640] Key type ._llcrypt unregistered [ 7615.097302] Key type ._llcrypt registered [ 7615.099592] Key type .llcrypt registered [ 7616.351542] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 00:10:10 (1781237410) [ 7618.496879] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7618.530093] alg: No test for adler32 (adler32-zlib) [ 7619.644027] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 7619.927181] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 7621.641434] Key type lgssc registered [ 7622.911140] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7669.385893] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7673.469803] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7694.379561] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7696.077592] Lustre: Mounted lustre-client [ 7716.858588] Lustre: Unmounted lustre-client [ 7745.220554] Key type lgssc unregistered [ 7745.465498] LNet: 167084:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7745.473836] LNetError: 167084:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7745.491492] LNet: Removed LNI 192.168.203.55@tcp [ 7746.072371] Key type .llcrypt unregistered [ 7746.075755] Key type ._llcrypt unregistered [ 7759.769412] Key type ._llcrypt registered [ 7759.777579] Key type .llcrypt registered [ 7760.364467] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7760.390936] alg: No test for adler32 (adler32-zlib) [ 7761.521336] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 7761.702658] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 7763.378955] Key type lgssc registered [ 7765.267471] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7816.326675] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7820.506704] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7840.532577] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7842.390787] Lustre: Mounted lustre-client [ 7849.960781] Lustre: Unmounted lustre-client [ 7874.468530] Key type lgssc unregistered [ 7874.812886] LNet: 170056:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7874.826295] LNetError: 170056:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7874.851676] LNet: Removed LNI 192.168.203.55@tcp [ 7875.427367] Key type .llcrypt unregistered [ 7875.429868] Key type ._llcrypt unregistered [ 7891.565679] Key type ._llcrypt registered [ 7891.568443] Key type .llcrypt registered [ 7892.850886] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 00:14:46 (1781237686) [ 7895.703910] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7895.756529] alg: No test for adler32 (adler32-zlib) [ 7897.078792] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 7897.503322] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 7899.359184] Key type lgssc registered [ 7900.782331] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7951.606976] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7955.842465] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7975.526234] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7977.381282] Lustre: Mounted lustre-client [ 7997.407456] Lustre: Unmounted lustre-client [ 8026.714478] Key type lgssc unregistered [ 8026.940865] LNet: 173721:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8026.947334] LNetError: 173721:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8026.964183] LNet: Removed LNI 192.168.203.55@tcp [ 8027.564463] Key type .llcrypt unregistered [ 8027.567614] Key type ._llcrypt unregistered [ 8041.655208] Key type ._llcrypt registered [ 8041.662602] Key type .llcrypt registered [ 8042.325119] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8042.338246] alg: No test for adler32 (adler32-zlib) [ 8043.660889] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 8043.953034] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 8045.655300] Key type lgssc registered [ 8047.038528] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8096.035566] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8099.895399] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8120.735453] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8122.450063] Lustre: Mounted lustre-client [ 8131.583662] Lustre: Unmounted lustre-client [ 8162.244266] Key type lgssc unregistered [ 8162.562383] LNet: 176694:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8162.580828] LNetError: 176694:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8162.601890] LNet: Removed LNI 192.168.203.55@tcp [ 8163.255365] Key type .llcrypt unregistered [ 8163.258132] Key type ._llcrypt unregistered [ 8179.003218] Key type ._llcrypt registered [ 8179.006175] Key type .llcrypt registered [ 8180.342948] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 00:19:33 (1781237973) [ 8201.973503] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8202.004981] alg: No test for adler32 (adler32-zlib) [ 8203.078843] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 8203.273599] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 8204.945905] Key type lgssc registered [ 8205.940626] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8289.206875] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8293.432634] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8316.261498] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8358.519205] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 00:22:31 (1781238151) [ 8388.800712] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8392.827141] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8412.081819] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8414.741662] Lustre: Mounted lustre-client [ 8419.382229] Lustre: Unmounted lustre-client [ 8447.908724] Key type lgssc unregistered [ 8448.205549] LNet: 183617:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8448.220214] LNetError: 183617:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8448.245575] LNet: Removed LNI 192.168.203.55@tcp [ 8448.959810] Key type .llcrypt unregistered [ 8448.961837] Key type ._llcrypt unregistered [ 8468.800066] Key type ._llcrypt registered [ 8468.802428] Key type .llcrypt registered [ 8469.957418] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8471.938756] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 00:24:25 (1781238265) [ 8494.172990] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8494.205567] alg: No test for adler32 (adler32-zlib) [ 8495.373772] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 8495.654331] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 8497.402135] Key type lgssc registered [ 8498.770628] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8590.351665] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8594.413604] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8615.069882] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 8633.266890] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 8636.042839] Lustre: Mounted lustre-client [ 8654.097720] Lustre: Unmounted lustre-client [ 8793.311903] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8797.817348] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8819.699263] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8868.182778] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 00:31:01 (1781238661) [ 8870.100343] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8871.812722] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 00:31:05 (1781238665) [ 8963.543433] Key type lgssc unregistered [ 8963.867957] LNet: 194852:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8963.875180] LNetError: 194852:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8963.904388] LNet: Removed LNI 192.168.203.55@tcp [ 8964.552470] Key type .llcrypt unregistered [ 8964.554323] Key type ._llcrypt unregistered [ 8980.560392] Key type ._llcrypt registered [ 8980.562240] Key type .llcrypt registered [ 8981.877368] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 00:32:55 (1781238775) [ 9022.696166] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9022.750858] alg: No test for adler32 (adler32-zlib) [ 9023.772274] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 9023.975404] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 9025.655368] Key type lgssc registered [ 9026.837495] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9067.584065] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9070.898357] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9106.006672] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9134.253075] Key type lgssc unregistered [ 9134.622742] LNet: 198498:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9134.634046] LNetError: 198498:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9134.666631] LNet: Removed LNI 192.168.203.55@tcp [ 9135.506560] Key type .llcrypt unregistered [ 9135.517050] Key type ._llcrypt unregistered [ 9153.215524] Key type ._llcrypt registered [ 9153.218819] Key type .llcrypt registered [ 9154.635493] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 00:35:48 (1781238948) [ 9157.615964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9157.720439] alg: No test for adler32 (adler32-zlib) [ 9158.814698] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 9159.060351] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 9160.751208] Key type lgssc registered [ 9161.959089] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9206.238570] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9209.716233] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9229.735241] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9232.485991] Lustre: Mounted lustre-client [ 9242.611072] Lustre: lustre-MDT0000-mdc-ffff97ef8a0fe000: Connection to lustre-MDT0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9252.851265] Lustre: lustre-MDT0001-mdc-ffff97ef8a0fe000: Connection to lustre-MDT0001 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9263.071203] Lustre: 199488:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781239042/real 1781239042] req@000000003772f387 x1867764417511424/t0(0) o400->MGC192.168.203.155@tcp@192.168.203.155@tcp:26/25 lens 224/224 e 0 to 1 dl 1781239058 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9263.095549] LustreError: MGC192.168.203.155@tcp: Connection to MGS (at 192.168.203.155@tcp) was lost; in progress operations using this service will fail [ 9273.396579] Lustre: Evicted from MGS (at 192.168.203.155@tcp) after server handle changed from 0x233bcbdcec58dc84 to 0x233bcbdcec58f5c8 [ 9273.429692] Lustre: MGC192.168.203.155@tcp: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 9298.427472] LustreError: lustre-MDT0000-mdc-ffff97ef8a0fe000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9298.472898] Lustre: lustre-MDT0000-mdc-ffff97ef8a0fe000: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 9298.502251] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9299.980457] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9303.720701] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9305.495946] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9308.467591] Lustre: Unmounted lustre-client [ 9340.909389] Key type lgssc unregistered [ 9341.132451] LNet: 203314:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9341.144539] LNetError: 203314:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9341.161411] LNet: Removed LNI 192.168.203.55@tcp [ 9341.693143] Key type .llcrypt unregistered [ 9341.695235] Key type ._llcrypt unregistered [ 9354.911156] Key type ._llcrypt registered [ 9354.914419] Key type .llcrypt registered [ 9356.337160] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 00:39:09 (1781239149) [ 9358.481517] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9358.502694] alg: No test for adler32 (adler32-zlib) [ 9359.556364] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 9359.743114] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 9361.407151] Key type lgssc registered [ 9362.512822] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9406.505451] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9412.329342] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9467.082935] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9470.850108] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9503.775897] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9524.399299] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9559.348436] Key type lgssc unregistered [ 9559.649315] LNet: 208430:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9559.658340] LNetError: 208430:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9559.695301] LNet: Removed LNI 192.168.203.55@tcp [ 9560.407648] Key type .llcrypt unregistered [ 9560.413840] Key type ._llcrypt unregistered [ 9604.711941] Key type ._llcrypt registered [ 9604.716537] Key type .llcrypt registered [ 9606.576749] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 00:43:19 (1781239399) [ 9650.144688] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9650.175656] alg: No test for adler32 (adler32-zlib) [ 9651.583402] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [ 9652.024279] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [ 9653.823536] Key type lgssc registered [ 9655.754729] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9736.275812] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9740.908218] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9762.580476] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9802.543427] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 00:46:36 (1781239596) [ 9810.749705] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 00:46:44 (1781239604) [ 9848.307434] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9852.170235] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9872.686912] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9875.440995] Lustre: Mounted lustre-client [ 9881.282273] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9883.779920] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9885.694177] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9887.851176] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9890.799497] Lustre: lustre-MDT0000-mdc-ffff97ef91148000: Connection to lustre-MDT0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9906.144065] Lustre: 210324:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781239685/real 1781239685] req@00000000878d512e x1867764934387456/t0(0) o400->MGC192.168.203.155@tcp@192.168.203.155@tcp:26/25 lens 224/224 e 0 to 1 dl 1781239701 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9906.210942] LustreError: MGC192.168.203.155@tcp: Connection to MGS (at 192.168.203.155@tcp) was lost; in progress operations using this service will fail [ 9916.424243] Lustre: Evicted from MGS (at 192.168.203.155@tcp) after server handle changed from 0xecaae706a53158aa to 0xecaae706a5315a1d [ 9916.436957] Lustre: MGC192.168.203.155@tcp: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 9922.582028] Lustre: lustre-MDT0000-mdc-ffff97ef91148000: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 9930.265222] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9932.135930] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9936.000567] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9941.996969] Lustre: lustre-MDT0000-mdc-ffff97ef91148000: Connection to lustre-MDT0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9958.368380] Lustre: 210325:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781239737/real 1781239737] req@00000000d3bf88c0 x1867764934395904/t0(0) o400->MGC192.168.203.155@tcp@192.168.203.155@tcp:26/25 lens 224/224 e 0 to 1 dl 1781239753 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9958.410835] LustreError: MGC192.168.203.155@tcp: Connection to MGS (at 192.168.203.155@tcp) was lost; in progress operations using this service will fail [ 9958.463575] Lustre: Evicted from MGS (at 192.168.203.155@tcp) after server handle changed from 0xecaae706a5315a1d to 0xecaae706a5315e14 [ 9958.484626] Lustre: MGC192.168.203.155@tcp: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 9964.050872] Lustre: lustre-MDT0000-mdc-ffff97ef91148000: Connection restored to 192.168.203.155@tcp (at 192.168.203.155@tcp) [ 9973.409911] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9975.560130] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9980.132508] Lustre: Unmounted lustre-client [10053.500566] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10058.683208] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10082.431909] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10082.994250] Lustre: Mounted lustre-client [10090.929957] Lustre: Unmounted lustre-client [10120.667960] Key type lgssc unregistered [10120.973529] LNet: 220606:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10120.982955] LNetError: 220606:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10121.004763] LNet: Removed LNI 192.168.203.55@tcp [10121.898764] Key type .llcrypt unregistered [10121.901484] Key type ._llcrypt unregistered [10140.681775] Key type ._llcrypt registered [10140.690511] Key type .llcrypt registered [10142.089754] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 00:52:15 (1781239935) [10147.163741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10147.233594] alg: No test for adler32 (adler32-zlib) [10148.511846] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [10148.921323] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [10150.591766] Key type lgssc registered [10152.158579] Lustre: Echo OBD driver; http://www.lustre.org/ [10203.399393] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10207.876817] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10228.434670] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10229.998389] Lustre: Mounted lustre-client [10236.783326] Lustre: Unmounted lustre-client [10300.749478] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10305.151733] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10324.403385] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10325.356722] Lustre: Mounted lustre-client [10335.278359] Lustre: Unmounted lustre-client [10403.659463] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10408.778583] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10429.178462] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10429.956516] Lustre: Mounted lustre-client [10432.144375] Lustre: Unmounted lustre-client [10461.538669] Key type lgssc unregistered [10461.832342] LNet: 227400:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10461.841438] LNetError: 227400:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10461.874732] LNet: Removed LNI 192.168.203.55@tcp [10462.556890] Key type .llcrypt unregistered [10462.559111] Key type ._llcrypt unregistered [10480.597577] Key type ._llcrypt registered [10480.599946] Key type .llcrypt registered [10482.079860] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 00:57:55 (1781240275) [10486.382989] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10486.397602] alg: No test for adler32 (adler32-zlib) [10487.610274] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [10487.851811] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [10489.559342] Key type lgssc registered [10491.085491] Lustre: Echo OBD driver; http://www.lustre.org/ [10539.186424] Key type lgssc unregistered [10539.471724] LNet: 229726:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10539.479725] LNetError: 229726:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10539.499343] LNet: Removed LNI 192.168.203.55@tcp [10540.193571] Key type .llcrypt unregistered [10540.196715] Key type ._llcrypt unregistered [10571.631231] Key type ._llcrypt registered [10571.635900] Key type .llcrypt registered [10572.227583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10572.239876] alg: No test for adler32 (adler32-zlib) [10573.328755] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [10573.594760] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [10575.311275] Key type lgssc registered [10576.865208] Lustre: Echo OBD driver; http://www.lustre.org/ [10661.377530] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10665.569909] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10689.169465] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10742.660967] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10744.740594] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 01:02:18 (1781240538) [10775.511026] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10779.898234] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10801.415412] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10824.569669] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [10827.486428] Lustre: Mounted lustre-client [10832.881349] Lustre: lustre-OST0001-osc-ffff97ef900b9000: Connection to lustre-OST0001 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [10840.770872] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10851.163470] Lustre: Unmounted lustre-client [10897.161246] Key type lgssc unregistered [10897.394828] LNet: 236659:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10897.420032] LNetError: 236659:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10897.443515] LNet: Removed LNI 192.168.203.55@tcp [10898.127727] Key type .llcrypt unregistered [10898.132213] Key type ._llcrypt unregistered [10929.758860] Key type ._llcrypt registered [10929.760275] Key type .llcrypt registered [10930.832209] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 01:05:24 (1781240724) [10945.269155] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10945.341591] alg: No test for adler32 (adler32-zlib) [10946.499532] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [10946.794732] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [10948.578474] Key type lgssc registered [10949.832032] Lustre: Echo OBD driver; http://www.lustre.org/ [11003.916556] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11007.898179] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11039.133583] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 01:07:12 (1781240832) [11071.742414] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11076.118288] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11096.945395] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11098.771710] Lustre: Mounted lustre-client [11106.712293] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11115.260125] Lustre: Unmounted lustre-client [11221.688115] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11226.365986] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11281.127311] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11281.959205] Lustre: Mounted lustre-client [11285.455510] Lustre: Unmounted lustre-client [11317.406612] Key type lgssc unregistered [11317.865217] LNet: 244119:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11317.887417] LNetError: 244119:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11317.933867] LNet: Removed LNI 192.168.203.55@tcp [11319.115737] Key type .llcrypt unregistered [11319.130335] Key type ._llcrypt unregistered [11351.166656] Key type ._llcrypt registered [11351.175341] Key type .llcrypt registered [11351.778311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11351.805410] alg: No test for adler32 (adler32-zlib) [11353.034094] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [11353.351766] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [11355.039212] Key type lgssc registered [11356.886689] Lustre: Echo OBD driver; http://www.lustre.org/ [11449.784497] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11455.058488] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11479.239196] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11522.179293] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11524.113833] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 01:15:17 (1781241317) [11556.718129] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11561.095402] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11583.610699] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11587.911561] Lustre: Mounted lustre-client [11592.249291] Lustre: Unmounted lustre-client [11621.797911] Key type lgssc unregistered [11622.072195] LNet: 250582:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11622.082082] LNetError: 250582:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11622.119293] LNet: Removed LNI 192.168.203.55@tcp [11622.692131] Key type .llcrypt unregistered [11622.695820] Key type ._llcrypt unregistered [11638.612653] Key type ._llcrypt registered [11638.616645] Key type .llcrypt registered [11639.824458] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11641.402361] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 01:17:15 (1781241435) [11650.753302] Key type .llcrypt unregistered [11650.759508] Key type ._llcrypt unregistered [11659.662741] Key type ._llcrypt registered [11659.667265] Key type .llcrypt registered [11660.118206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11660.130178] alg: No test for adler32 (adler32-zlib) [11661.226481] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [11661.485293] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [11663.175328] Key type lgssc registered [11664.595890] Lustre: Echo OBD driver; http://www.lustre.org/ [11721.942705] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11737.766327] Lustre: Mounted lustre-client [11741.161895] Lustre: Unmounted lustre-client [11767.991516] Key type lgssc unregistered [11768.304596] LNet: 254011:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11768.315169] LNetError: 254011:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11768.347539] LNet: Removed LNI 192.168.203.55@tcp [11769.006308] Key type .llcrypt unregistered [11769.012191] Key type ._llcrypt unregistered [11785.208682] Key type ._llcrypt registered [11785.211925] Key type .llcrypt registered [11786.613805] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 01:19:40 (1781241580) [11789.085847] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11789.228642] alg: No test for adler32 (adler32-zlib) [11790.373656] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [11790.616736] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [11792.255205] Key type lgssc registered [11793.699043] Lustre: Echo OBD driver; http://www.lustre.org/ [11877.132726] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11912.790882] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11916.649572] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11919.577607] Lustre: Mounted lustre-client [11922.982180] Lustre: Unmounted lustre-client [11958.801661] Key type lgssc unregistered [11959.070471] LNet: 257472:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11959.089069] LNetError: 257472:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11959.113220] LNet: Removed LNI 192.168.203.55@tcp [11959.819773] Key type .llcrypt unregistered [11959.823178] Key type ._llcrypt unregistered [11974.975335] Key type ._llcrypt registered [11974.982401] Key type .llcrypt registered [11976.610360] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 01:22:49 (1781241769) [11978.807086] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11978.862408] alg: No test for adler32 (adler32-zlib) [11979.980456] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [11980.254730] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [11981.943432] Key type lgssc registered [11983.104305] Lustre: Echo OBD driver; http://www.lustre.org/ [12029.992387] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12034.524344] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12054.052314] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12056.610896] Lustre: Mounted lustre-client [12061.672303] Lustre: lustre-MDT0000-mdc-ffff97ef91613800: Connection to lustre-MDT0000 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [12066.535854] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12066.569110] LustreError: 260542:0:(file.c:6103:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12066.878077] LustreError: MGC192.168.203.155@tcp: Connection to MGS (at 192.168.203.155@tcp) was lost; in progress operations using this service will fail [12066.936509] Lustre: Unmounted lustre-client [12088.880134] Key type lgssc unregistered [12089.123626] LNet: 260957:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12089.130797] LNetError: 260957:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12089.159784] LNet: Removed LNI 192.168.203.55@tcp [12089.775650] Key type .llcrypt unregistered [12089.779872] Key type ._llcrypt unregistered [12105.491480] Key type ._llcrypt registered [12105.494406] Key type .llcrypt registered [12106.637672] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 01:25:00 (1781241900) [12109.002637] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12109.077281] alg: No test for adler32 (adler32-zlib) [12110.248698] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [12110.593161] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [12112.367126] Key type lgssc registered [12113.588803] Lustre: Echo OBD driver; http://www.lustre.org/ [12159.470279] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12163.457879] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12185.164677] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12187.098310] Lustre: Mounted lustre-client [12192.235991] Lustre: lustre-MDT0001-mdc-ffff97ef847e3000: Connection to lustre-MDT0001 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [12194.329456] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12197.134188] Lustre: Unmounted lustre-client [12216.287771] Key type lgssc unregistered [12216.545346] LNet: 264453:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12216.554173] LNetError: 264453:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12216.571389] LNet: Removed LNI 192.168.203.55@tcp [12217.144858] Key type .llcrypt unregistered [12217.150918] Key type ._llcrypt unregistered [12231.260763] Key type ._llcrypt registered [12231.263470] Key type .llcrypt registered [12232.419842] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 01:27:06 (1781242026) [12250.994873] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12251.019812] alg: No test for adler32 (adler32-zlib) [12252.092790] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [12252.315190] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [12253.994103] Key type lgssc registered [12255.235395] Lustre: Echo OBD driver; http://www.lustre.org/ [12365.748251] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12368.409514] Lustre: Mounted lustre-client [12409.334522] Lustre: lustre-MDT0001-mdc-ffff97ef91c55800: Connection to lustre-MDT0001 (at 192.168.203.155@tcp) was lost; in progress operations using this service will wait for recovery to complete [12419.846496] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12430.210632] Lustre: Unmounted lustre-client [12457.962400] Key type lgssc unregistered [12458.212571] LNet: 270212:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12458.236445] LNetError: 270212:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12458.259945] LNet: Removed LNI 192.168.203.55@tcp [12458.909663] Key type .llcrypt unregistered [12458.917244] Key type ._llcrypt unregistered [12478.834522] Key type ._llcrypt registered [12478.838454] Key type .llcrypt registered [12480.376673] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 01:31:13 (1781242273) [12482.747729] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12484.588757] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 01:31:18 (1781242278) [12486.675799] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12488.930220] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 01:31:22 (1781242282) [12491.072101] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12492.935455] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 01:31:26 (1781242286) [12494.860874] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12497.031405] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 01:31:30 (1781242290) [12498.977572] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12501.259916] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 01:31:34 (1781242294) [12511.441321] Key type .llcrypt unregistered [12511.442768] Key type ._llcrypt unregistered [12519.837671] Key type ._llcrypt registered [12519.840048] Key type .llcrypt registered [12520.315579] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12520.332879] alg: No test for adler32 (adler32-zlib) [12521.431865] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [12521.725166] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [12523.471177] Key type lgssc registered [12524.854713] Lustre: Echo OBD driver; http://www.lustre.org/ [12606.348246] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12610.419701] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12632.390744] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12633.962421] Lustre: Mounted lustre-client [12636.628516] Lustre: Unmounted lustre-client [12675.254350] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 01:34:28 (1781242468) [12709.490153] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12713.341818] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12745.567996] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12756.548533] Lustre: Mounted lustre-client [12759.034093] Lustre: Unmounted lustre-client [12798.244155] LNet: 1 local NIs in recovery (showing 1): 192.168.203.55@tcp [12803.655830] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 01:36:37 (1781242597) [13442.812784] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13446.489217] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13475.750841] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13567.950184] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 01:49:21 (1781243361) [13577.567924] Key type lgssc unregistered [13577.855497] LNet: 283474:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13577.864829] LNetError: 283474:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13577.897786] LNet: Removed LNI 192.168.203.55@tcp [13578.582492] Key type .llcrypt unregistered [13578.585644] Key type ._llcrypt unregistered [13684.121080] Key type ._llcrypt registered [13684.128106] Key type .llcrypt registered [13684.457438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13684.474157] alg: No test for adler32 (adler32-zlib) [13685.467715] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [13685.644845] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [13687.271373] Key type lgssc registered [13688.147650] Lustre: Echo OBD driver; http://www.lustre.org/ [13745.605263] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13748.085919] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13761.985489] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13796.933788] Key type lgssc unregistered [13797.120158] LNet: 287071:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13797.129819] LNetError: 287071:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13797.144649] LNet: Removed LNI 192.168.203.55@tcp [13797.605529] Key type .llcrypt unregistered [13797.607277] Key type ._llcrypt unregistered [13830.073219] Key type ._llcrypt registered [13830.076209] Key type .llcrypt registered [13830.837873] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 01:53:45 (1781243625) [13843.328713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13843.338933] alg: No test for adler32 (adler32-zlib) [13844.276827] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [13844.419145] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [13846.047158] Key type lgssc registered [13846.789825] Lustre: Echo OBD driver; http://www.lustre.org/ [13877.442761] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 01:54:31 (1781243671) [13885.025426] Key type lgssc unregistered [13885.214755] LNet: 290622:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13885.222144] LNetError: 290622:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13885.243482] LNet: Removed LNI 192.168.203.55@tcp [13885.723038] Key type .llcrypt unregistered [13885.726695] Key type ._llcrypt unregistered [13892.093020] Key type ._llcrypt registered [13892.099432] Key type .llcrypt registered [13892.369126] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13892.391814] alg: No test for adler32 (adler32-zlib) [13893.439686] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [13893.659464] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [13895.295148] Key type lgssc registered [13896.046191] Lustre: Echo OBD driver; http://www.lustre.org/ [13946.216816] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13952.138513] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13981.221822] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14252.529287] LNet: Added LNI 192.168.203.55@tcp50 [8/256/0/180] [14254.919431] LNet: Removed LNI 192.168.203.55@tcp [14257.356085] Lustre: 294934:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff97ef91c56800: config has no valid NIDs, force dynamic NIDs [14258.580392] Lustre: Mounted lustre-client [14258.764879] Lustre: Unmounted lustre-client [14261.924437] Key type lgssc unregistered [14262.326519] LNet: 295193:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14262.333655] LNetError: 295193:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14262.352562] LNet: Removed LNI 192.168.203.55@tcp50 [14262.932516] Key type .llcrypt unregistered [14262.936746] Key type ._llcrypt unregistered [14282.677520] Key type ._llcrypt registered [14282.680047] Key type .llcrypt registered [14283.146550] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14283.159699] alg: No test for adler32 (adler32-zlib) [14284.251921] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [14284.546557] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [14286.264181] Key type lgssc registered [14287.928876] Lustre: Echo OBD driver; http://www.lustre.org/ [14353.179975] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14358.652153] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14368.587712] LNet: Added LNI 192.168.203.55@tcp50 [8/256/0/180] [14369.334290] LNet: Removed LNI 192.168.203.55@tcp [14370.710646] Lustre: 297842:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff97ef8541d000: config has no valid NIDs, force dynamic NIDs [14371.961000] Lustre: Mounted lustre-client [14372.491166] Lustre: Unmounted lustre-client [14405.863859] Key type lgssc unregistered [14406.259171] LNet: 298712:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14406.272616] LNetError: 298712:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14406.290277] LNet: Removed LNI 192.168.203.55@tcp50 [14406.889671] Key type .llcrypt unregistered [14406.893383] Key type ._llcrypt unregistered [14418.099877] Key type ._llcrypt registered [14418.101989] Key type .llcrypt registered [14419.310622] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 02:03:33 (1781244213) [14436.308983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14436.361725] alg: No test for adler32 (adler32-zlib) [14437.791826] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [14438.159594] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [14439.839208] Key type lgssc registered [14441.683426] Lustre: Echo OBD driver; http://www.lustre.org/ [14526.935189] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14532.642334] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14554.590205] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14599.409696] Key type lgssc unregistered [14599.728129] LNet: 303376:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14599.753583] LNetError: 303376:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14599.768267] LNet: Removed LNI 192.168.203.55@tcp [14600.541316] Key type .llcrypt unregistered [14600.543819] Key type ._llcrypt unregistered [14613.904670] Key type ._llcrypt registered [14613.907540] Key type .llcrypt registered [14615.454833] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 02:06:48 (1781244408) [14618.155277] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14618.257112] alg: No test for adler32 (adler32-zlib) [14619.420785] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [14619.714168] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [14621.416251] Key type lgssc registered [14622.916449] Lustre: Echo OBD driver; http://www.lustre.org/ [14673.697099] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14677.777936] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14697.609672] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14700.535229] Lustre: Mounted lustre-client [14760.457601] Lustre: Mounted fs15246-client [14777.561673] Lustre: Unmounted fs15246-client [14810.591318] Lustre: Unmounted lustre-client [14839.171854] Key type lgssc unregistered [14839.417736] LNet: 308153:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14839.432739] LNetError: 308153:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14839.458656] LNet: Removed LNI 192.168.203.55@tcp [14840.240667] Key type .llcrypt unregistered [14840.247074] Key type ._llcrypt unregistered [14851.193554] Key type ._llcrypt registered [14851.197202] Key type .llcrypt registered [14852.867305] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 02:10:46 (1781244646) [14855.314482] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14855.411909] alg: No test for adler32 (adler32-zlib) [14856.650365] Lustre: Lustre: Build Version: 2.17.53_61_g0d029bc [14856.931636] LNet: Added LNI 192.168.203.55@tcp [8/256/0/180] [14858.655205] Key type lgssc registered [14860.686258] Lustre: Echo OBD driver; http://www.lustre.org/ [15010.080459] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [15014.029737] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [15035.850814] Lustre: DEBUG MARKER: oleg355-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [15136.494793] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14897 sec ========= 02:15:30 (1781244930) [15138.023398] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 02:15:31 (1781244931) === [15141.620521] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 02:15:35 (1781244935) === [15175.546150] Key type lgssc unregistered [15175.863519] LNet: 317333:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15175.875965] LNetError: 317333:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [15175.895941] LNet: Removed LNI 192.168.203.55@tcp [15176.554391] Key type .llcrypt unregistered [15176.565226] Key type ._llcrypt unregistered