[ 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 436204882 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.001014] APIC: Switch to symmetric I/O mode setup [ 0.003366] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.006012] kvm-guest: setup PV IPIs [ 0.009000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.010005] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.011015] pid_max: default: 32768 minimum: 301 [ 0.012159] LSM: Security Framework initializing [ 0.013062] Yama: becoming mindful. [ 0.014051] SELinux: Initializing. [ 0.015104] *** VALIDATE selinux *** [ 0.023783] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028824] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030150] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031129] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032140] *** VALIDATE tmpfs *** [ 0.033497] *** VALIDATE proc *** [ 0.034281] *** VALIDATE cgroup *** [ 0.035014] *** VALIDATE cgroup2 *** [ 0.036296] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037162] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039032] Spectre V2 : User space: Vulnerable [ 0.040011] Speculative Store Bypass: Vulnerable [ 0.043025] debug: unmapping init [mem 0xffffffffa2e59000-0xffffffffa2e60fff] [ 0.045191] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046812] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047031] ... version: 2 [ 0.048016] ... bit width: 48 [ 0.049015] ... generic registers: 4 [ 0.050014] ... value mask: 0000ffffffffffff [ 0.051016] ... max period: 00007fffffffffff [ 0.052019] ... fixed-purpose events: 3 [ 0.053018] ... event mask: 000000070000000f [ 0.054330] rcu: Hierarchical SRCU implementation. [ 0.056473] smp: Bringing up secondary CPUs ... [ 0.057677] x86: Booting SMP configuration: [ 0.058033] .... node #0, CPUs: #1 #2 #3 [ 0.061419] smp: Brought up 1 node, 4 CPUs [ 0.063016] smpboot: Max logical packages: 1 [ 0.064024] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.140276] node 0 deferred pages initialised in 74ms [ 0.143284] devtmpfs: initialized [ 0.144249] x86/mm: Memory block size: 128MB [ 0.146587] gcov: version magic: 0x41383552 [ 0.148142] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.151125] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.154357] pinctrl core: initialized pinctrl subsystem [ 0.156164] [ 0.156722] ************************************************************* [ 0.160014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.162016] ** ** [ 0.165015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.168012] ** ** [ 0.171014] ** This means that this kernel is built to expose internal ** [ 0.173015] ** IOMMU data structures, which may compromise security on ** [ 0.176012] ** your system. ** [ 0.179014] ** ** [ 0.182018] ** If you see this message and you are not debugging the ** [ 0.185012] ** kernel, report this immediately to your vendor! ** [ 0.187017] ** ** [ 0.190015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.192027] ************************************************************* [ 0.195664] NET: Registered protocol family 16 [ 0.197561] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.200057] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.203072] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.207119] cpuidle: using governor menu [ 0.208846] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.209534] PCI: Using configuration type 1 for base access [ 0.210135] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.217127] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.220041] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.224030] cryptd: max_cpu_qlen set to 1000 [ 0.227287] ACPI: Added _OSI(Module Device) [ 0.229024] ACPI: Added _OSI(Processor Device) [ 0.231014] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.232019] ACPI: Added _OSI(Processor Aggregator Device) [ 0.238278] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.243320] ACPI: Interpreter enabled [ 0.245072] ACPI: PM: (supports S0 S3 S4 S5) [ 0.247011] ACPI: Using IOAPIC for interrupt routing [ 0.248105] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.252381] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.263385] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.266056] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.269029] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.273103] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.278643] acpiphp: Slot [2] registered [ 0.280184] acpiphp: Slot [5] registered [ 0.282181] acpiphp: Slot [6] registered [ 0.284175] acpiphp: Slot [3] registered [ 0.287155] acpiphp: Slot [4] registered [ 0.289152] acpiphp: Slot [7] registered [ 0.291125] acpiphp: Slot [8] registered [ 0.292138] acpiphp: Slot [9] registered [ 0.294124] acpiphp: Slot [10] registered [ 0.295129] acpiphp: Slot [11] registered [ 0.297115] acpiphp: Slot [12] registered [ 0.298122] acpiphp: Slot [13] registered [ 0.300129] acpiphp: Slot [14] registered [ 0.301169] acpiphp: Slot [15] registered [ 0.303168] acpiphp: Slot [16] registered [ 0.304114] acpiphp: Slot [17] registered [ 0.306115] acpiphp: Slot [18] registered [ 0.308189] acpiphp: Slot [19] registered [ 0.309101] acpiphp: Slot [20] registered [ 0.311148] acpiphp: Slot [21] registered [ 0.312111] acpiphp: Slot [22] registered [ 0.314117] acpiphp: Slot [23] registered [ 0.315186] acpiphp: Slot [24] registered [ 0.317103] acpiphp: Slot [25] registered [ 0.318085] acpiphp: Slot [26] registered [ 0.320109] acpiphp: Slot [27] registered [ 0.321142] acpiphp: Slot [28] registered [ 0.323116] acpiphp: Slot [29] registered [ 0.324126] acpiphp: Slot [30] registered [ 0.325107] acpiphp: Slot [31] registered [ 0.327070] PCI host bridge to bus 0000:00 [ 0.329024] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.331025] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.334037] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.337031] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.339027] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.342028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.345203] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.348058] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.351448] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.360019] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.365063] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.368036] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.370020] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.373024] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.376841] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.379843] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.382053] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.384745] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.389014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.399777] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.404015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.410890] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.418016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.423019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.441016] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.452346] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.458017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.463833] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.474016] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.489351] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.491435] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.493457] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.496422] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.499271] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.504802] iommu: Default domain type: Passthrough [ 0.506379] SCSI subsystem initialized [ 0.508197] ACPI: bus type USB registered [ 0.510113] usbcore: registered new interface driver usbfs [ 0.512101] usbcore: registered new interface driver hub [ 0.514173] usbcore: registered new device driver usb [ 0.516190] pps_core: LinuxPPS API ver. 1 registered [ 0.518013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.521078] PTP clock support registered [ 0.523138] EDAC MC: Ver: 3.0.0 [ 0.525121] PCI: Using ACPI for IRQ routing [ 0.526509] NetLabel: Initializing [ 0.528016] NetLabel: domain hash size = 128 [ 0.530015] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.532078] NetLabel: unlabeled traffic allowed by default [ 0.534122] vgaarb: loaded [ 0.535389] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.538017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.546902] clocksource: Switched to clocksource kvm-clock [ 0.654133] VFS: Disk quotas dquot_6.6.0 [ 0.655839] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.658577] *** VALIDATE ramfs *** [ 0.659932] *** VALIDATE hugetlbfs *** [ 0.661775] pnp: PnP ACPI init [ 0.664347] pnp: PnP ACPI: found 6 devices [ 0.689818] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.693903] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.696493] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.699154] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.701686] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.704420] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.707430] NET: Registered protocol family 2 [ 0.710106] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.715252] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.718818] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.724587] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.728478] TCP: Hash tables configured (established 65536 bind 65536) [ 0.731736] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.735429] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.738743] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.742250] NET: Registered protocol family 1 [ 0.745247] RPC: Registered named UNIX socket transport module. [ 0.747411] RPC: Registered udp transport module. [ 0.748581] RPC: Registered tcp transport module. [ 0.749725] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.751313] NET: Registered protocol family 44 [ 0.752763] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.755449] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.757638] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.760299] PCI: CLS 0 bytes, default 64 [ 0.762343] Unpacking initramfs... [ 2.193791] debug: unmapping init [mem 0xffff95e83cc64000-0xffff95e83ffcffff] [ 2.199565] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.202557] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.206980] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.728271] Initialise system trusted keyrings [ 2.731374] Key type blacklist registered [ 2.733717] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.745360] zbud: loaded [ 2.749140] *** VALIDATE nfs *** [ 2.751731] *** VALIDATE nfs4 *** [ 2.753813] pstore: using deflate compression [ 2.758058] Platform Keyring initialized [ 2.874430] NET: Registered protocol family 38 [ 2.876321] Key type asymmetric registered [ 2.877909] Asymmetric key parser 'x509' registered [ 2.880211] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.883774] io scheduler mq-deadline registered [ 2.885277] io scheduler kyber registered [ 2.887143] io scheduler bfq registered [ 2.888714] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.892036] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.895226] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.898095] ACPI: Power Button [PWRF] [ 2.903511] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.909490] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.922435] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.952491] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.983846] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.988747] Non-volatile memory driver v1.3 [ 2.991145] Linux agpgart interface v0.103 [ 3.028265] virtio_blk virtio1: [vda] 149952 512-byte logical blocks (76.8 MB/73.2 MiB) [ 3.031717] vda: detected capacity change from 0 to 76775424 [ 3.052686] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.055571] vdb: detected capacity change from 0 to 1073741824 [ 3.062098] libphy: Fixed MDIO Bus: probed [ 3.067286] usbcore: registered new interface driver usbserial_generic [ 3.070207] usbserial: USB Serial support registered for generic [ 3.072818] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.077476] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.079538] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.082309] mousedev: PS/2 mouse device common for all mice [ 3.084900] rtc_cmos 00:05: RTC can wake from S4 [ 3.087786] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.091613] rtc_cmos 00:05: registered as rtc0 [ 3.094133] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.094461] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.098853] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.100347] intel_pstate: CPU model not supported [ 3.106814] hid: raw HID events driver (C) Jiri Kosina [ 3.109121] usbcore: registered new interface driver usbhid [ 3.111319] usbhid: USB HID core driver [ 3.113120] drop_monitor: Initializing network drop monitor service [ 3.115560] Initializing XFRM netlink socket [ 3.117373] NET: Registered protocol family 10 [ 3.120808] Segment Routing with IPv6 [ 3.122318] NET: Registered protocol family 17 [ 3.124262] mpls_gso: MPLS GSO support [ 3.130176] RAS: Correctable Errors collector initialized. [ 3.132416] AVX version of gcm_enc/dec engaged. [ 3.134173] AES CTR mode by8 optimization enabled [ 3.210406] sched_clock: Marking stable (3210297418, 0)->(4146672770, -936375352) [ 3.214985] registered taskstats version 1 [ 3.217252] Loading compiled-in X.509 certificates [ 3.219353] zswap: loaded using pool lzo/zbud [ 3.244092] Key type big_key registered [ 3.257662] Key type encrypted registered [ 3.258861] ima: No TPM chip found, activating TPM-bypass! [ 3.260385] ima: Allocated hash algorithm: sha1 [ 3.261636] ima: No architecture policies found [ 3.262859] evm: Initialising EVM extended attributes: [ 3.264224] evm: security.selinux [ 3.265084] evm: security.ima [ 3.266031] evm: security.capability [ 3.266884] evm: HMAC attrs: 0x1 [ 3.268740] rtc_cmos 00:05: setting system clock to 2026-09-09 06:33:21 UTC (1788935601) [ 3.274205] debug: unmapping init [mem 0xffffffffa3e03000-0xffffffffa3ffffff] [ 3.276638] debug: unmapping init [mem 0xffffffffa2b82000-0xffffffffa2e58fff] [ 3.282119] Write protecting the kernel read-only data: 28672k [ 3.285199] debug: unmapping init [mem 0xffffffffa1203000-0xffffffffa13fffff] [ 3.287370] debug: unmapping init [mem 0xffffffffa1b14000-0xffffffffa1bfffff] [ 3.316386] 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.322484] systemd[1]: Detected virtualization kvm. [ 3.323903] systemd[1]: Detected architecture x86-64. [ 3.325350] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.346686] systemd[1]: No hostname configured. [ 3.347938] systemd[1]: Set hostname to . [ 3.349553] random: systemd: uninitialized urandom read (16 bytes read) [ 3.351567] systemd[1]: Initializing machine ID from random generator. [ 3.476938] random: systemd: uninitialized urandom read (16 bytes read) [ 3.481259] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.485129] random: systemd: uninitialized urandom read (16 bytes read) [ 3.486881] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.490325] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Sockets. Starting Journal Service... Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Reached target Initrd Root Device. Starting Setup Virtual Console... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.100790] device-mapper: uevent: version 1.0.3 [ 4.103773] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.868304] virtio_net virtio0 ens2: renamed from eth0 [ 4.875375] scsi host0: ata_piix [ 4.881962] scsi host1: ata_piix [ 4.887056] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.889785] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.789666] random: crng init done [ 9.799154] random: 7 urandom warning(s) missed due to ratelimiting [ 9.849534] dracut-initqueue[586]: RTNETLINK answers: File exists 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... [ 11.605186] 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 dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ 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. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 14.925498] printk: systemd: 26 output lines suppressed due to ratelimiting [ 15.715561] SELinux: Disabled at runtime. [ 15.886935] 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) [ 15.926338] systemd[1]: Detected virtualization kvm. [ 15.934635] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 17.747225] systemd[1]: initrd-switch-root.service: Succeeded. [ 17.751981] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 17.771870] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 17.780555] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 17.793183] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 17.842046] systemd[1]: Starting Journal Service... Starting Journal Service... [ 17.887723] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ OK ] Stopped target Switch Root. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting POSIX Message Queue File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on initctl Compatibility Named Pipe. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Initrd Root File System. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting Kernel Debug File System... [ 18.379652] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Mounting Huge Pages File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Reached target RPC Port Mapper. [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 Process Core Dump Socket. [ OK ] Reached target Paths. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 19.808537] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 20.644117] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 20.748373] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 21.270634] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 21.319549] EDAC sbridge: Ver: 1.1.2 [ 25.403602] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit)[ 26.582571] NFS: Registering the id_resolver key type [ 26.585868] Key type id_resolver registered [ 26.588274] Key type id_legacy registered [*** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started OpenSSH server daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Login Service. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg449-client login: [ 100.476263] libcfs: loading out-of-tree module taints kernel. [ 100.653888] Key type ._llcrypt registered [ 100.655457] Key type .llcrypt registered [ 101.597962] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 101.613829] alg: No test for adler32 (adler32-zlib) [ 103.297854] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 104.358358] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 106.191270] Key type lgssc registered [ 108.229709] Lustre: Echo OBD driver; http://www.lustre.org/ [ 287.202457] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 292.921299] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 311.294302] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing check_logdir /tmp/testlogs/ [ 312.801256] Lustre: lustre-OST0000-osc-ffff95e888965800: disconnect after 23s idle [ 316.423733] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing yml_node [ 320.839758] Lustre: DEBUG MARKER: Client: 2.17.58.40 [ 323.515860] Lustre: DEBUG MARKER: MDS: 2.17.58.40 [ 326.459417] Lustre: DEBUG MARKER: OSS: 2.17.58.40 [ 328.097734] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Wed Sep 9 02:38:45 EDT 2026 [ 346.951851] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 348.505547] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 351.124443] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 361.420092] Lustre: Unmounted lustre-client [ 454.861009] hrtimer: interrupt took 8134527 ns [ 472.759623] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 479.090453] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 502.640057] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 539.965706] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 02:42:16 (1788936136) [ 542.514193] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 544.975349] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 02:42:21 (1788936141) [ 583.538104] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 589.765773] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 612.806858] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 613.429864] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 621.548216] Lustre: Unmounted lustre-client [ 650.166543] Key type lgssc unregistered [ 650.473112] LNet: 15069:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 650.481476] LNetError: 15069:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 650.503503] LNet: Removed LNI 192.168.204.49@tcp [ 651.478301] Key type .llcrypt unregistered [ 651.485135] Key type ._llcrypt unregistered [ 668.242400] Key type ._llcrypt registered [ 668.243928] Key type .llcrypt registered [ 669.541197] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 02:44:26 (1788936266) [ 670.979221] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 671.028442] alg: No test for adler32 (adler32-zlib) [ 672.135140] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 672.341863] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 674.048466] Key type lgssc registered [ 675.350656] Lustre: Echo OBD driver; http://www.lustre.org/ [ 761.680634] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 848.122407] Lustre: Unmounted lustre-client [ 878.051510] Key type lgssc unregistered [ 878.313627] LNet: 18872:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 878.330756] LNetError: 18872:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 878.351470] LNet: Removed LNI 192.168.204.49@tcp [ 879.259429] Key type .llcrypt unregistered [ 879.266458] Key type ._llcrypt unregistered [ 891.570981] Key type ._llcrypt registered [ 891.576904] Key type .llcrypt registered [ 893.156541] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 02:48:09 (1788936489) [ 896.652188] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 896.809828] alg: No test for adler32 (adler32-zlib) [ 897.978352] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 898.268084] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 899.912317] Key type lgssc registered [ 901.288984] Lustre: Echo OBD driver; http://www.lustre.org/ [ 957.829768] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 964.269075] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 986.129507] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 988.988384] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 998.746988] Lustre: Unmounted lustre-client [ 1027.224713] Key type lgssc unregistered [ 1027.525932] LNet: 22474:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1027.532484] LNetError: 22474:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1027.552419] LNet: Removed LNI 192.168.204.49@tcp [ 1028.200691] Key type .llcrypt unregistered [ 1028.204218] Key type ._llcrypt unregistered [ 1039.370373] Key type ._llcrypt registered [ 1039.373086] Key type .llcrypt registered [ 1040.552062] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 02:50:37 (1788936637) [ 1042.664524] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1042.696223] alg: No test for adler32 (adler32-zlib) [ 1043.680271] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 1044.068358] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 1045.807260] Key type lgssc registered [ 1047.009329] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1102.405643] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1108.837715] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1131.458750] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1132.706353] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 1141.829879] Lustre: Unmounted lustre-client [ 1169.073935] Key type lgssc unregistered [ 1169.266972] LNet: 25660:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1169.277878] LNetError: 25660:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1169.301574] LNet: Removed LNI 192.168.204.49@tcp [ 1169.840764] Key type .llcrypt unregistered [ 1169.843321] Key type ._llcrypt unregistered [ 1185.278866] Key type ._llcrypt registered [ 1185.280506] Key type .llcrypt registered [ 1186.765531] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 02:53:03 (1788936783) [ 1189.546056] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1189.582209] alg: No test for adler32 (adler32-zlib) [ 1190.615634] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 1191.052919] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 1192.815244] Key type lgssc registered [ 1194.328951] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1249.991743] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1256.829821] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1279.327367] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1291.356264] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 1301.491370] Lustre: lustre-MDT0000-mdc-ffff95e88534c800: Connection to lustre-MDT0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1311.720228] Lustre: lustre-MDT0001-mdc-ffff95e88534c800: Connection to lustre-MDT0001 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1316.322107] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1321.958289] Lustre: 26614:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788936904/real 1788936904] req@000000009d6b61f3 x1875836182469248/t0(0) o400->MGC192.168.204.149@tcp@192.168.204.149@tcp:26/25 lens 224/224 e 0 to 1 dl 1788936920 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1321.992670] LustreError: MGC192.168.204.149@tcp: Connection to MGS (at 192.168.204.149@tcp) was lost; in progress operations using this service will fail [ 1326.693619] Lustre: Unmounted lustre-client [ 1354.853711] Key type lgssc unregistered [ 1355.264700] LNet: 29318:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1355.280130] LNetError: 29318:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1355.332072] LNet: Removed LNI 192.168.204.49@tcp [ 1356.072728] Key type .llcrypt unregistered [ 1356.075673] Key type ._llcrypt unregistered [ 1374.864481] Key type ._llcrypt registered [ 1374.868344] Key type .llcrypt registered [ 1376.561838] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 02:56:12 (1788936972) [ 1378.872791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1378.884468] alg: No test for adler32 (adler32-zlib) [ 1380.054246] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 1380.381479] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 1382.119466] Key type lgssc registered [ 1383.560539] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1439.954364] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1444.218755] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1467.105873] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1469.854624] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 1480.184396] Lustre: lustre-OST0000-osc-ffff95e884327000: Connection to lustre-OST0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1485.456597] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1495.846141] Lustre: Unmounted lustre-client [ 1533.430082] Key type lgssc unregistered [ 1533.772591] LNet: 32929:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1533.779578] LNetError: 32929:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1533.803175] LNet: Removed LNI 192.168.204.49@tcp [ 1534.520846] Key type .llcrypt unregistered [ 1534.524326] Key type ._llcrypt unregistered [ 1549.882050] Key type ._llcrypt registered [ 1549.883543] Key type .llcrypt registered [ 1551.122531] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 02:59:07 (1788937147) [ 1553.890157] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1554.071734] alg: No test for adler32 (adler32-zlib) [ 1555.201284] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 1555.604806] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 1557.383263] Key type lgssc registered [ 1558.987587] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1621.367424] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1627.146525] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1648.740740] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1651.210780] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 1658.177344] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1662.752441] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1674.179221] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-09 3h01m11s [ 1676.658772] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1681.897150] Lustre: lustre-MDT0000-mdc-ffff95e892940000: Connection to lustre-MDT0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1687.796940] LustreError: 36732:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff95e892940000: can't stat MDS #0: rc = -19 [ 1689.444651] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1700.332816] LustreError: MGC192.168.204.149@tcp: Connection to MGS (at 192.168.204.149@tcp) was lost; in progress operations using this service will fail [ 1700.369731] Lustre: Evicted from MGS (at 192.168.204.149@tcp) after server handle changed from 0x6f3608020305912a to 0x6f360802030592ea [ 1700.382890] Lustre: MGC192.168.204.149@tcp: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [ 1701.430420] LustreError: lustre-MDT0000-mdc-ffff95e892940000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1701.450701] Lustre: lustre-MDT0000-mdc-ffff95e892940000: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [ 1709.301462] Lustre: DEBUG MARKER: Wait for df (36732) ... [ 1711.134911] Lustre: DEBUG MARKER: done [ 1712.799901] Lustre: Unmounted lustre-client [ 1752.195658] Key type lgssc unregistered [ 1752.555877] LNet: 37750:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1752.563165] LNetError: 37750:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1752.580801] LNet: Removed LNI 192.168.204.49@tcp [ 1753.432579] Key type .llcrypt unregistered [ 1753.434944] Key type ._llcrypt unregistered [ 1786.264525] Key type ._llcrypt registered [ 1786.265611] Key type .llcrypt registered [ 1788.713367] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 03:03:04 (1788937384) [ 1791.809701] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1791.823465] alg: No test for adler32 (adler32-zlib) [ 1793.099151] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 1793.564818] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 1795.311284] Key type lgssc registered [ 1797.543693] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1852.496369] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1860.200741] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1883.326952] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1885.048423] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 1889.031670] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-09 3h04m46s [ 1890.813606] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1897.549187] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1900.253821] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1920.480133] Lustre: 41896:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788937498/real 1788937498] req@00000000c42b7e25 x1875836813711488/t0(0) o101->lustre-MDT0000-mdc-ffff95e89298d000@192.168.204.149@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788937518 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1920.512137] Lustre: lustre-MDT0000-mdc-ffff95e89298d000: Connection to lustre-MDT0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1920.550194] Lustre: lustre-MDT0000-mdc-ffff95e89298d000: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [ 1941.804740] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1944.906504] Lustre: DEBUG MARKER: done [ 1949.558185] Lustre: Unmounted lustre-client [ 1978.707876] Key type lgssc unregistered [ 1978.947934] LNet: 42782:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1978.962226] LNetError: 42782:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1978.979820] LNet: Removed LNI 192.168.204.49@tcp [ 1979.902384] Key type .llcrypt unregistered [ 1979.904445] Key type ._llcrypt unregistered [ 2012.034083] Key type ._llcrypt registered [ 2012.038819] Key type .llcrypt registered [ 2013.960540] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 03:06:50 (1788937610) [ 2016.134731] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2018.380813] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 03:06:54 (1788937614) [ 2023.127322] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2023.207857] alg: No test for adler32 (adler32-zlib) [ 2024.380104] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 2024.850641] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 2026.682264] Key type lgssc registered [ 2028.365994] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2057.532490] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 03:07:34 (1788937654) [ 2091.636599] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2097.877468] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2119.607273] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2122.427906] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 2126.472274] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2131.189182] Lustre: Unmounted lustre-client [ 2149.699409] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2199.766074] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2205.647583] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2206.194600] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 2210.443816] Lustre: Unmounted lustre-client [ 2281.334982] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2288.647093] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2289.240930] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 2293.671406] Lustre: Unmounted lustre-client [ 2309.594328] Lustre: DEBUG MARKER: files compared the same [ 2332.588845] Key type lgssc unregistered [ 2332.818656] LNet: 50419:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2332.825740] LNetError: 50419:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2332.851261] LNet: Removed LNI 192.168.204.49@tcp [ 2333.660341] Key type .llcrypt unregistered [ 2333.661772] Key type ._llcrypt unregistered [ 2349.891476] Key type ._llcrypt registered [ 2349.893621] Key type .llcrypt registered [ 2351.401785] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 03:12:27 (1788937947) [ 2354.027841] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2354.044368] alg: No test for adler32 (adler32-zlib) [ 2355.259254] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 2355.550145] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 2357.228362] Key type lgssc registered [ 2358.685508] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2413.130762] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2419.574230] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2440.450740] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2443.014167] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 2450.169264] Lustre: Unmounted lustre-client [ 2477.830698] Key type lgssc unregistered [ 2478.158947] LNet: 54001:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2478.182474] LNetError: 54001:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2478.217136] LNet: Removed LNI 192.168.204.49@tcp [ 2479.107558] Key type .llcrypt unregistered [ 2479.120370] Key type ._llcrypt unregistered [ 2494.807541] Key type ._llcrypt registered [ 2494.811557] Key type .llcrypt registered [ 2495.971455] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 03:14:52 (1788938092) [ 2498.372332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2498.418375] alg: No test for adler32 (adler32-zlib) [ 2499.507412] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 2499.825699] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 2501.495266] Key type lgssc registered [ 2502.638219] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2588.867537] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2623.278819] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2629.447789] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2657.186911] Key type lgssc unregistered [ 2657.447923] LNet: 57481:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2657.454824] LNetError: 57481:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2657.472023] LNet: Removed LNI 192.168.204.49@tcp [ 2658.211613] Key type .llcrypt unregistered [ 2658.219903] Key type ._llcrypt unregistered [ 2678.463215] Key type ._llcrypt registered [ 2678.467790] Key type .llcrypt registered [ 2680.188384] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 03:17:56 (1788938276) [ 2684.147181] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2684.225431] alg: No test for adler32 (adler32-zlib) [ 2685.379433] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 2685.638331] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 2687.360479] Key type lgssc registered [ 2688.828736] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2764.960427] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 2772.433916] Lustre: Unmounted lustre-client [ 2813.449832] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 03:20:09 (1788938409) [ 2938.128395] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2959.909111] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 2967.376633] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2967.381820] LustreError: 63954:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff95e884023000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2967.406098] Lustre: 58462:0:(llite_lib.c:4343:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.149@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2977.790833] Lustre: Unmounted lustre-client [ 3030.930621] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 03:23:47 (1788938627) [ 3042.393881] Key type lgssc unregistered [ 3042.610312] LNet: 65163:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3042.621811] LNetError: 65163:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3042.656033] LNet: Removed LNI 192.168.204.49@tcp [ 3043.318466] Key type .llcrypt unregistered [ 3043.325898] Key type ._llcrypt unregistered [ 3059.378265] Key type ._llcrypt registered [ 3059.382043] Key type .llcrypt registered [ 3060.166543] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3060.182328] alg: No test for adler32 (adler32-zlib) [ 3061.306538] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 3061.744170] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 3063.505336] Key type lgssc registered [ 3065.019523] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3119.259614] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3125.472129] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3146.367741] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3148.228773] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 3155.567788] Lustre: Unmounted lustre-client [ 3184.862745] Key type lgssc unregistered [ 3185.175085] LNet: 68174:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3185.188537] LNetError: 68174:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3185.207623] LNet: Removed LNI 192.168.204.49@tcp [ 3186.023285] Key type .llcrypt unregistered [ 3186.027660] Key type ._llcrypt unregistered [ 3199.779144] Key type ._llcrypt registered [ 3199.797139] Key type .llcrypt registered [ 3200.101152] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3200.139559] alg: No test for adler32 (adler32-zlib) [ 3201.265984] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 3201.584758] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 3203.255683] Key type lgssc registered [ 3204.713959] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3343.547570] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3350.292334] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3374.971292] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3380.815549] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 3383.870145] Lustre: Unmounted lustre-client [ 3421.150731] Key type lgssc unregistered [ 3421.457976] LNet: 71604:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3421.483933] LNetError: 71604:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3421.501735] LNet: Removed LNI 192.168.204.49@tcp [ 3422.409689] Key type .llcrypt unregistered [ 3422.415140] Key type ._llcrypt unregistered [ 3439.463870] Key type ._llcrypt registered [ 3439.466226] Key type .llcrypt registered [ 3441.087401] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 03:30:37 (1788939037) [ 3443.838025] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3443.905977] alg: No test for adler32 (adler32-zlib) [ 3445.094453] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 3445.471527] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 3447.216915] Key type lgssc registered [ 3448.750852] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3509.635738] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3516.281257] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3541.803554] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3548.783689] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 3554.637374] Lustre: Unmounted lustre-client [ 3555.089696] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 3557.912143] Lustre: Unmounted lustre-client [ 3586.033304] Key type lgssc unregistered [ 3586.336451] LNet: 75288:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3586.355321] LNetError: 75288:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3586.365385] LNet: Removed LNI 192.168.204.49@tcp [ 3587.185528] Key type .llcrypt unregistered [ 3587.189224] Key type ._llcrypt unregistered [ 3599.810915] Key type ._llcrypt registered [ 3599.816787] Key type .llcrypt registered [ 3600.292832] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3600.317774] alg: No test for adler32 (adler32-zlib) [ 3601.453401] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 3601.848664] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 3603.663241] Key type lgssc registered [ 3605.365660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3670.119987] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3677.641195] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3700.715737] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3712.080277] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 3718.576854] Lustre: Unmounted lustre-client [ 3748.914175] Key type lgssc unregistered [ 3749.261816] LNet: 78318:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3749.267324] LNetError: 78318:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3749.281658] LNet: Removed LNI 192.168.204.49@tcp [ 3750.179365] Key type .llcrypt unregistered [ 3750.181579] Key type ._llcrypt unregistered [ 3770.746095] Key type ._llcrypt registered [ 3770.748830] Key type .llcrypt registered [ 3772.018564] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 03:36:08 (1788939368) [ 3774.539550] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3774.723207] alg: No test for adler32 (adler32-zlib) [ 3775.967314] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 3776.208507] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 3777.887303] Key type lgssc registered [ 3779.386429] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3835.728599] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3842.908891] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3871.675667] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3874.532553] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 3881.661750] Lustre: lustre: root_squash is set to 0:0 [ 3889.767362] Lustre: lustre: nosquash_nids is cleared [ 3895.835870] Lustre: lustre: root_squash is set to 500:500 [ 3915.354714] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.49@tcp 192.168.0.[2,10]@tcp [ 3929.149313] Lustre: lustre: nosquash_nids set to 192.168.204.49/24@tcp [ 3932.178112] Lustre: Unmounted lustre-client [ 3960.177837] Key type lgssc unregistered [ 3960.613721] LNet: 82308:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3960.642516] LNetError: 82308:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3960.677210] LNet: Removed LNI 192.168.204.49@tcp [ 3961.703524] Key type .llcrypt unregistered [ 3961.705169] Key type ._llcrypt unregistered [ 3980.588508] Key type ._llcrypt registered [ 3980.595120] Key type .llcrypt registered [ 3982.431472] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 03:39:38 (1788939578) [ 3985.414270] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3987.333077] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 03:39:44 (1788939584) [ 3990.130132] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3990.278203] alg: No test for adler32 (adler32-zlib) [ 3991.426714] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 3991.758420] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 3993.416116] Key type lgssc registered [ 3994.812413] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4050.565720] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4056.886773] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4078.657179] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4080.445637] Lustre: lustre: root_squash is set to 500:500 [ 4080.490196] Lustre: lustre: nosquash_nids set to 192.168.204.49/24@tcp [ 4081.551378] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 4090.103186] Lustre: Unmounted lustre-client [ 4128.105667] Key type lgssc unregistered [ 4128.465153] LNet: 86269:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4128.474182] LNetError: 86269:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4128.490467] LNet: Removed LNI 192.168.204.49@tcp [ 4129.274812] Key type .llcrypt unregistered [ 4129.278136] Key type ._llcrypt unregistered [ 4145.878714] Key type ._llcrypt registered [ 4145.880621] Key type .llcrypt registered [ 4147.920957] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4150.939058] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 03:42:26 (1788939746) [ 4176.569206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4176.806674] alg: No test for adler32 (adler32-zlib) [ 4178.085377] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 4178.556431] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 4180.263263] Key type lgssc registered [ 4181.618897] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4283.289890] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4288.822314] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4313.238173] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4387.874804] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4396.941317] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4418.346795] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4430.744673] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 4462.761629] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95e882fc8000.ost_server_uuid 50 [ 4464.426404] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95e882fc8000.ost_server_uuid in FULL state after 0 sec [ 4464.921481] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 4468.178083] Lustre: Unmounted lustre-client [ 4469.315596] Lustre: Unmounted lustre-client [ 4523.498804] Key type lgssc unregistered [ 4523.646770] LNet: 93932:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4523.652849] LNetError: 93932:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4523.670408] LNet: Removed LNI 192.168.204.49@tcp [ 4524.168376] Key type .llcrypt unregistered [ 4524.173579] Key type ._llcrypt unregistered [ 4554.757630] Key type ._llcrypt registered [ 4554.759605] Key type .llcrypt registered [ 4556.479263] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 03:49:12 (1788940152) [ 4580.014754] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4580.036526] alg: No test for adler32 (adler32-zlib) [ 4581.137936] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 4581.340798] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 4583.136642] Key type lgssc registered [ 4584.555676] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4678.202582] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4683.231364] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4710.359842] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4713.259957] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 4733.921296] Lustre: 95291:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788940316/real 1788940316] req@000000003958f84e x1875839737140480/t0(0) o400->lustre-OST0000-osc-ffff95e882d73800@192.168.204.149@tcp:28/4 lens 224/224 e 0 to 1 dl 1788940332 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4733.961526] Lustre: lustre-OST0000-osc-ffff95e882d73800: Connection to lustre-OST0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4739.105328] Lustre: 95292:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788940321/real 1788940321] req@000000000af695f6 x1875839737140992/t0(0) o400->lustre-OST0000-osc-ffff95e882d73800@192.168.204.149@tcp:28/4 lens 224/224 e 0 to 1 dl 1788940337 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4744.171502] Lustre: lustre-MDT0000-mdc-ffff95e882d73800: Connection to lustre-MDT0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4744.222432] Lustre: lustre-OST0000-osc-ffff95e882d73800: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [ 4745.247746] Lustre: 95291:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788940327/real 1788940327] req@00000000e6e9baff x1875839737141504/t0(0) o400->lustre-OST0000-osc-ffff95e882d73800@192.168.204.149@tcp:28/4 lens 224/224 e 0 to 1 dl 1788940343 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4750.303418] Lustre: 95292:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788940332/real 1788940332] req@00000000c37bb265 x1875839737142016/t0(0) o400->lustre-OST0000-osc-ffff95e882d73800@192.168.204.149@tcp:28/4 lens 224/224 e 0 to 1 dl 1788940348 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4765.663179] Lustre: 95293:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788940347/real 1788940347] req@000000002237d27a x1875839737144704/t0(0) o400->MGC192.168.204.149@tcp@192.168.204.149@tcp:26/25 lens 224/224 e 0 to 1 dl 1788940363 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4765.741964] LustreError: MGC192.168.204.149@tcp: Connection to MGS (at 192.168.204.149@tcp) was lost; in progress operations using this service will fail [ 4765.788918] Lustre: Evicted from MGS (at 192.168.204.149@tcp) after server handle changed from 0x70f25ac8906e5784 to 0x70f25ac8906e5913 [ 4765.810245] Lustre: MGC192.168.204.149@tcp: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [ 4779.963807] Lustre: lustre-MDT0000-mdc-ffff95e882d73800: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [ 4785.825881] Lustre: Unmounted lustre-client [ 4816.340699] Key type lgssc unregistered [ 4816.610237] LNet: 99596:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4816.633990] LNetError: 99596:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4816.669246] LNet: Removed LNI 192.168.204.49@tcp [ 4817.705171] Key type .llcrypt unregistered [ 4817.708381] Key type ._llcrypt unregistered [ 4838.306081] Key type ._llcrypt registered [ 4838.307794] Key type .llcrypt registered [ 4839.835794] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 03:53:56 (1788940436) [ 4855.002021] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4855.036050] alg: No test for adler32 (adler32-zlib) [ 4856.276028] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 4856.631908] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 4858.319301] Key type lgssc registered [ 4859.896604] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4904.811376] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5010.691667] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5017.210196] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5047.725726] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5050.790152] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 5053.255668] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5055.010349] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 03:57:31 (1788940651) [ 5306.081142] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5316.455969] Lustre: Unmounted lustre-client [ 5456.555395] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5463.185785] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5490.329859] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5542.870371] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 04:05:39 (1788941139) [ 5646.517531] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5652.849845] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5675.843716] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5676.396559] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 5681.925195] Lustre: Unmounted lustre-client [ 5717.149606] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 04:08:33 (1788941313) [ 5814.314528] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5820.357461] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5843.224886] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5844.022871] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 5847.877733] Lustre: Unmounted lustre-client [ 5877.791734] Key type lgssc unregistered [ 5878.076569] LNet: 122644:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5878.092232] LNetError: 122644:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5878.117364] LNet: Removed LNI 192.168.204.49@tcp [ 5878.801191] Key type .llcrypt unregistered [ 5878.804487] Key type ._llcrypt unregistered [ 5894.610730] Key type ._llcrypt registered [ 5894.615324] Key type .llcrypt registered [ 5896.053944] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 04:11:32 (1788941492) [ 5898.447945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5898.473950] alg: No test for adler32 (adler32-zlib) [ 5899.517354] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 5899.775201] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 5901.543782] Key type lgssc registered [ 5903.018506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5953.345964] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5959.703022] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5981.957820] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5983.638518] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 5999.591728] Lustre: Unmounted lustre-client [ 6028.395281] Key type lgssc unregistered [ 6028.639669] LNet: 126231:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6028.643666] LNetError: 126231:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6028.672066] LNet: Removed LNI 192.168.204.49@tcp [ 6029.322690] Key type .llcrypt unregistered [ 6029.324516] Key type ._llcrypt unregistered [ 6043.444552] Key type ._llcrypt registered [ 6043.446455] Key type .llcrypt registered [ 6044.854325] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 04:14:01 (1788941641) [ 6047.500230] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6047.522667] alg: No test for adler32 (adler32-zlib) [ 6048.597516] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 6048.884981] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 6050.647746] Key type lgssc registered [ 6052.306533] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6107.606746] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6114.232712] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6135.647738] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6138.594198] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 6143.977149] Lustre: lustre-OST0000-osc-ffff95e886099800: Connection to lustre-OST0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6156.034827] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff95e886099800.ost_server_uuid 50 [ 6157.621635] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6169.112078] Lustre: Unmounted lustre-client [ 6190.852511] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 04:16:27 (1788941787) [ 6224.070884] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6229.684784] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6251.906042] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6275.924199] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6276.580848] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 6281.707457] Lustre: lustre-OST0000-osc-ffff95e8903fc000: Connection to lustre-OST0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6309.024809] Lustre: Unmounted lustre-client [ 6358.226049] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 04:19:15 (1788941955) [ 6394.730512] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6401.434829] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6427.467123] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6451.897601] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6452.499932] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 6457.844995] Lustre: lustre-OST0000-osc-ffff95e8887a4000: Connection to lustre-OST0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6474.362328] Lustre: Unmounted lustre-client [ 6534.202398] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 04:22:10 (1788942130) [ 6641.461284] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6648.454722] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6673.935684] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6748.539669] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6754.520237] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6776.610372] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6817.407782] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 6858.108477] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6863.906895] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 4 sec [ 6866.279799] Lustre: Unmounted lustre-client [ 6897.168431] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 04:28:13 (1788942493) [ 6932.783780] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6938.415784] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6959.493996] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6992.345901] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7032.473049] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 7074.629981] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7076.044347] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7078.903425] Lustre: lustre-OST0001-osc-ffff95e88380c000: Connection to lustre-OST0001 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7088.350140] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7098.758290] Lustre: Unmounted lustre-client [ 7152.561745] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 04:32:29 (1788942749) [ 7188.484113] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7193.942933] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7216.223812] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7216.875391] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 7238.832942] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7240.224804] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7255.485373] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95e891e2d800.ost_server_uuid 50 [ 7256.887908] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95e891e2d800.ost_server_uuid in IDLE state after 0 sec [ 7259.595156] Lustre: Unmounted lustre-client [ 7259.888921] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7259.893714] Lustre: Skipped 3 previous similar messages [ 7259.995598] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 7260.977147] LustreError: 149671:0:(sec.c:409:import_sec_validate_get()) import 00000000b6e14f79 (NEW) with no sec [ 7317.042348] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 04:35:13 (1788942913) [ 7351.627745] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7357.646313] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7378.915895] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7398.586127] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7398.953552] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7399.067870] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 7410.705090] Lustre: Unmounted lustre-client [ 7410.717974] Lustre: Skipped 1 previous similar message [ 7446.152647] Key type lgssc unregistered [ 7446.545895] LNet: 153074:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7446.557552] LNetError: 153074:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7446.603621] LNet: Removed LNI 192.168.204.49@tcp [ 7447.426765] Key type .llcrypt unregistered [ 7447.431676] Key type ._llcrypt unregistered [ 7463.625678] Key type ._llcrypt registered [ 7463.628717] Key type .llcrypt registered [ 7465.123666] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 04:37:41 (1788943061) [ 7466.402665] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7466.418280] alg: No test for adler32 (adler32-zlib) [ 7467.461700] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 7467.768698] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 7469.488390] Key type lgssc registered [ 7470.789175] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7522.378476] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7527.598283] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7547.943138] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7567.046778] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7567.562310] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7568.724312] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 7573.777822] LustreError: lustre-MDT0001-mdc-ffff95e8879ce800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7573.789887] Lustre: lustre-MDT0001-mdc-ffff95e8879ce800: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [ 7579.510276] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7580.871670] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7602.183790] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7606.105166] Lustre: Unmounted lustre-client [ 7635.476664] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 04:40:32 (1788943232) [ 7716.055772] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7722.410439] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7743.136165] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7743.700545] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 7766.989523] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7768.452488] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7780.481517] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7790.813047] Lustre: Unmounted lustre-client [ 7833.331884] Key type lgssc unregistered [ 7833.705387] LNet: 162668:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7833.722933] LNetError: 162668:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7833.789871] LNet: Removed LNI 192.168.204.49@tcp [ 7835.029130] Key type .llcrypt unregistered [ 7835.036363] Key type ._llcrypt unregistered [ 7866.078778] Key type ._llcrypt registered [ 7866.080931] Key type .llcrypt registered [ 7867.434508] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 04:44:24 (1788943464) [ 7869.468180] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7869.573996] alg: No test for adler32 (adler32-zlib) [ 7870.552989] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 7870.820114] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 7872.583482] Key type lgssc registered [ 7873.668791] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7921.892748] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7926.933968] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7946.035029] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7948.884979] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 7996.090037] Lustre: Unmounted lustre-client [ 8037.535146] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8038.375234] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 8075.549478] Lustre: Unmounted lustre-client [ 8110.807505] Key type lgssc unregistered [ 8111.134908] LNet: 167112:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8111.145414] LNetError: 167112:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8111.164326] LNet: Removed LNI 192.168.204.49@tcp [ 8111.984329] Key type .llcrypt unregistered [ 8111.986609] Key type ._llcrypt unregistered [ 8128.625264] Key type ._llcrypt registered [ 8128.627895] Key type .llcrypt registered [ 8129.800949] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 04:48:46 (1788943726) [ 8131.894995] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8131.905479] alg: No test for adler32 (adler32-zlib) [ 8133.055380] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 8133.405864] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 8135.103263] Key type lgssc registered [ 8136.191843] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8190.837385] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8196.890771] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8216.861634] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8219.479339] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 8241.320766] Lustre: Unmounted lustre-client [ 8265.981654] Key type lgssc unregistered [ 8266.224189] LNet: 170877:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8266.231806] LNetError: 170877:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8266.254220] LNet: Removed LNI 192.168.204.49@tcp [ 8267.010777] Key type .llcrypt unregistered [ 8267.012506] Key type ._llcrypt unregistered [ 8278.876979] Key type ._llcrypt registered [ 8278.882681] Key type .llcrypt registered [ 8279.341622] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8279.359338] alg: No test for adler32 (adler32-zlib) [ 8280.498886] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 8280.847933] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 8282.575473] Key type lgssc registered [ 8283.989470] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8331.439159] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8336.953147] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8358.073663] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8360.650340] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 8371.011927] Lustre: Unmounted lustre-client [ 8399.744802] Key type lgssc unregistered [ 8400.020474] LNet: 173935:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8400.035926] LNetError: 173935:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8400.059659] LNet: Removed LNI 192.168.204.49@tcp [ 8400.924126] Key type .llcrypt unregistered [ 8400.927808] Key type ._llcrypt unregistered [ 8418.967356] Key type ._llcrypt registered [ 8418.969769] Key type .llcrypt registered [ 8420.471573] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 04:53:37 (1788944017) [ 8422.664534] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8422.725345] alg: No test for adler32 (adler32-zlib) [ 8423.877110] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 8424.173715] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 8425.892383] Key type lgssc registered [ 8427.277051] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8479.883596] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8485.358929] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8507.829648] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8510.509535] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 8530.390330] Lustre: Unmounted lustre-client [ 8559.353947] Key type lgssc unregistered [ 8559.666374] LNet: 177698:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8559.674225] LNetError: 177698:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8559.692265] LNet: Removed LNI 192.168.204.49@tcp [ 8560.622206] Key type .llcrypt unregistered [ 8560.632053] Key type ._llcrypt unregistered [ 8576.118930] Key type ._llcrypt registered [ 8576.127084] Key type .llcrypt registered [ 8576.518239] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8576.544171] alg: No test for adler32 (adler32-zlib) [ 8577.571923] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 8577.787523] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 8579.551972] Key type lgssc registered [ 8581.464466] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8632.639376] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8639.231872] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8665.861527] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8667.516445] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 8676.293782] Lustre: Unmounted lustre-client [ 8715.089552] Key type lgssc unregistered [ 8715.360809] LNet: 180762:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8715.371963] LNetError: 180762:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8715.396429] LNet: Removed LNI 192.168.204.49@tcp [ 8716.223945] Key type .llcrypt unregistered [ 8716.228239] Key type ._llcrypt unregistered [ 8733.392443] Key type ._llcrypt registered [ 8733.394883] Key type .llcrypt registered [ 8734.849234] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 04:58:51 (1788944331) [ 8757.661263] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8757.805386] alg: No test for adler32 (adler32-zlib) [ 8759.125693] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 8759.581665] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 8761.295420] Key type lgssc registered [ 8762.974052] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8848.013199] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8854.137277] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8877.897895] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8929.345944] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 05:02:06 (1788944526) [ 8961.968170] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8967.270808] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8986.016404] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8988.728378] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 8993.502026] Lustre: Unmounted lustre-client [ 9019.691855] Key type lgssc unregistered [ 9019.914884] LNet: 187866:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9019.919899] LNetError: 187866:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9019.934664] LNet: Removed LNI 192.168.204.49@tcp [ 9020.554415] Key type .llcrypt unregistered [ 9020.557356] Key type ._llcrypt unregistered [ 9035.504630] Key type ._llcrypt registered [ 9035.506208] Key type .llcrypt registered [ 9036.749832] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9038.385370] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 05:03:55 (1788944635) [ 9056.787572] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9056.960651] alg: No test for adler32 (adler32-zlib) [ 9058.108514] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 9058.402479] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 9060.199249] Key type lgssc registered [ 9061.385610] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9144.755325] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9150.160792] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9171.002182] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9191.936112] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9193.567519] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 9212.787104] Lustre: Unmounted lustre-client [ 9336.376982] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9341.113322] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9365.392925] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9402.151714] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 05:09:59 (1788944999) [ 9403.619829] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9405.184672] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 05:10:02 (1788945002) [ 9497.794207] Key type lgssc unregistered [ 9498.112926] LNet: 199290:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9498.121388] LNetError: 199290:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9498.146923] LNet: Removed LNI 192.168.204.49@tcp [ 9498.725502] Key type .llcrypt unregistered [ 9498.730760] Key type ._llcrypt unregistered [ 9514.544838] Key type ._llcrypt registered [ 9514.546823] Key type .llcrypt registered [ 9516.020394] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 05:11:52 (1788945112) [ 9559.452148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9559.494809] alg: No test for adler32 (adler32-zlib) [ 9560.538727] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 9560.889365] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 9562.616370] Key type lgssc registered [ 9563.893142] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9609.567328] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9615.274095] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9647.937517] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9683.349703] Key type lgssc unregistered [ 9683.672848] LNet: 203017:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9683.679067] LNetError: 203017:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9683.689187] LNet: Removed LNI 192.168.204.49@tcp [ 9684.546162] Key type .llcrypt unregistered [ 9684.549755] Key type ._llcrypt unregistered [ 9699.649717] Key type ._llcrypt registered [ 9699.651333] Key type .llcrypt registered [ 9700.905675] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 05:14:57 (1788945297) [ 9703.643453] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9703.658197] alg: No test for adler32 (adler32-zlib) [ 9704.774446] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 9705.130842] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 9706.863235] Key type lgssc registered [ 9708.280213] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9758.867728] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9764.311501] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9784.896273] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9787.597676] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [ 9803.242755] Lustre: lustre-MDT0000-mdc-ffff95e8903fa800: Connection to lustre-MDT0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9813.485701] Lustre: lustre-MDT0001-mdc-ffff95e8903fa800: Connection to lustre-MDT0001 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9824.738491] Lustre: 204006:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788945406/real 1788945406] req@00000000ea68c94e x1875845110121216/t0(0) o400->MGC192.168.204.149@tcp@192.168.204.149@tcp:26/25 lens 224/224 e 0 to 1 dl 1788945422 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9824.781395] LustreError: MGC192.168.204.149@tcp: Connection to MGS (at 192.168.204.149@tcp) was lost; in progress operations using this service will fail [ 9850.349465] Lustre: Evicted from MGS (at 192.168.204.149@tcp) after server handle changed from 0x908adf4bd6fc9814 to 0x908adf4bd6fcb414 [ 9850.359894] Lustre: MGC192.168.204.149@tcp: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [ 9876.002230] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9880.041750] LustreError: lustre-MDT0000-mdc-ffff95e8903fa800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9880.126944] Lustre: lustre-MDT0000-mdc-ffff95e8903fa800: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [ 9881.468243] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 4 sec [ 9887.919498] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9889.296673] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9892.203607] Lustre: Unmounted lustre-client [ 9918.227876] Key type lgssc unregistered [ 9918.605027] LNet: 207989:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9918.615332] LNetError: 207989:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9918.636785] LNet: Removed LNI 192.168.204.49@tcp [ 9919.447561] Key type .llcrypt unregistered [ 9919.451157] Key type ._llcrypt unregistered [ 9935.066585] Key type ._llcrypt registered [ 9935.068837] Key type .llcrypt registered [ 9936.438623] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 05:18:53 (1788945533) [ 9939.002835] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9939.025201] alg: No test for adler32 (adler32-zlib) [ 9940.189636] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [ 9940.404321] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [ 9942.048188] Key type lgssc registered [ 9943.067703] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9987.201098] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9992.746406] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10045.039243] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10050.558178] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10079.873319] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10096.071577] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10141.009638] Key type lgssc unregistered [10141.334207] LNet: 213275:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10141.347303] LNetError: 213275:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10141.379610] LNet: Removed LNI 192.168.204.49@tcp [10142.372854] Key type .llcrypt unregistered [10142.374576] Key type ._llcrypt unregistered [10183.283703] Key type ._llcrypt registered [10183.287362] Key type .llcrypt registered [10184.432824] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 05:23:01 (1788945781) [10218.091320] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10218.119051] alg: No test for adler32 (adler32-zlib) [10219.144738] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [10219.345626] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [10220.976383] Key type lgssc registered [10222.160154] Lustre: Echo OBD driver; http://www.lustre.org/ [10297.519115] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10302.826724] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10324.768469] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10364.122946] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 05:26:01 (1788945961) [10369.760438] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 05:26:06 (1788945966) [10406.414448] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10411.389190] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10432.439876] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10435.190343] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [10444.192880] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10446.905082] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10448.963533] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10450.775067] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10455.524473] Lustre: lustre-MDT0000-mdc-ffff95e88609e000: Connection to lustre-MDT0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [10471.775713] Lustre: 215168:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788946053/real 1788946053] req@00000000d4f3d876 x1875845649016960/t0(0) o400->MGC192.168.204.149@tcp@192.168.204.149@tcp:26/25 lens 224/224 e 0 to 1 dl 1788946069 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10471.811219] LustreError: MGC192.168.204.149@tcp: Connection to MGS (at 192.168.204.149@tcp) was lost; in progress operations using this service will fail [10471.829084] Lustre: Evicted from MGS (at 192.168.204.149@tcp) after server handle changed from 0xa1350690c3e1263b to 0xa1350690c3e12817 [10471.845234] Lustre: MGC192.168.204.149@tcp: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [10477.480610] Lustre: lustre-MDT0000-mdc-ffff95e88609e000: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [10486.312408] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10487.841654] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10491.264510] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10497.510183] Lustre: lustre-MDT0000-mdc-ffff95e88609e000: Connection to lustre-MDT0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [10512.863129] Lustre: 215169:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788946095/real 1788946095] req@0000000076347c27 x1875845649025408/t0(0) o400->MGC192.168.204.149@tcp@192.168.204.149@tcp:26/25 lens 224/224 e 0 to 1 dl 1788946111 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10512.894298] LustreError: MGC192.168.204.149@tcp: Connection to MGS (at 192.168.204.149@tcp) was lost; in progress operations using this service will fail [10512.916324] Lustre: Evicted from MGS (at 192.168.204.149@tcp) after server handle changed from 0xa1350690c3e12817 to 0xa1350690c3e12bc1 [10512.939693] Lustre: MGC192.168.204.149@tcp: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [10517.916776] Lustre: lustre-MDT0000-mdc-ffff95e88609e000: Connection restored to 192.168.204.149@tcp (at 192.168.204.149@tcp) [10524.005305] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10525.244718] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10528.509123] Lustre: Unmounted lustre-client [10593.309945] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10598.048764] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10623.159672] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10623.656967] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [10625.811921] Lustre: Unmounted lustre-client [10648.128368] Key type lgssc unregistered [10648.378756] LNet: 225741:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10648.385585] LNetError: 225741:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10648.403427] LNet: Removed LNI 192.168.204.49@tcp [10649.129577] Key type .llcrypt unregistered [10649.131907] Key type ._llcrypt unregistered [10664.388680] Key type ._llcrypt registered [10664.390398] Key type .llcrypt registered [10665.600805] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 05:31:02 (1788946262) [10669.875906] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10669.903153] alg: No test for adler32 (adler32-zlib) [10670.944158] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [10671.206213] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [10672.911322] Key type lgssc registered [10674.475858] Lustre: Echo OBD driver; http://www.lustre.org/ [10727.482744] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10732.110212] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10756.842357] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10758.530176] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [10764.538640] Lustre: Unmounted lustre-client [10829.122155] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10834.989370] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10855.869714] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10856.473908] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [10866.125248] Lustre: Unmounted lustre-client [10937.236054] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10942.821546] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10964.053228] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10964.762306] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [10967.526911] Lustre: Unmounted lustre-client [10992.109987] Key type lgssc unregistered [10992.420060] LNet: 232786:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10992.430296] LNetError: 232786:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10992.448917] LNet: Removed LNI 192.168.204.49@tcp [10993.216673] Key type .llcrypt unregistered [10993.218666] Key type ._llcrypt unregistered [11013.058687] Key type ._llcrypt registered [11013.060711] Key type .llcrypt registered [11014.779169] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 05:36:51 (1788946611) [11017.589444] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11017.670288] alg: No test for adler32 (adler32-zlib) [11018.889097] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [11019.092521] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [11020.767279] Key type lgssc registered [11022.057706] Lustre: Echo OBD driver; http://www.lustre.org/ [11071.328871] Key type lgssc unregistered [11071.692653] LNet: 235106:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11071.698301] LNetError: 235106:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11071.720857] LNet: Removed LNI 192.168.204.49@tcp [11072.371117] Key type .llcrypt unregistered [11072.374781] Key type ._llcrypt unregistered [11098.245384] Key type ._llcrypt registered [11098.248065] Key type .llcrypt registered [11098.806038] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11098.862798] alg: No test for adler32 (adler32-zlib) [11100.301353] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [11100.594525] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [11102.304502] Key type lgssc registered [11103.868587] Lustre: Echo OBD driver; http://www.lustre.org/ [11188.903648] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11194.160911] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11217.400663] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11259.875863] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11261.720910] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 05:40:58 (1788946858) [11295.426058] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11301.477237] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11322.217584] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11345.841322] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11347.600551] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [11353.067141] Lustre: lustre-OST0001-osc-ffff95e884eb9000: Connection to lustre-OST0001 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [11359.961781] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11370.394515] Lustre: Unmounted lustre-client [11405.523687] Key type lgssc unregistered [11405.752462] LNet: 242223:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11405.769589] LNetError: 242223:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11405.789035] LNet: Removed LNI 192.168.204.49@tcp [11406.458061] Key type .llcrypt unregistered [11406.462229] Key type ._llcrypt unregistered [11439.332515] Key type ._llcrypt registered [11439.335322] Key type .llcrypt registered [11440.867759] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 05:43:57 (1788947037) [11454.387022] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11454.428899] alg: No test for adler32 (adler32-zlib) [11455.602395] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [11455.936261] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [11457.679262] Key type lgssc registered [11458.872170] Lustre: Echo OBD driver; http://www.lustre.org/ [11513.619835] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11519.001473] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11544.903613] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 05:45:41 (1788947141) [11574.358300] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11581.647920] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11605.901883] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11608.629531] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [11619.905412] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11629.159765] Lustre: Unmounted lustre-client [11739.640222] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11744.769290] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11799.994182] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11800.746335] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [11804.187994] Lustre: Unmounted lustre-client [11834.953570] Key type lgssc unregistered [11835.265912] LNet: 249919:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11835.282807] LNetError: 249919:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11836.331307] LNet: Removed LNI 192.168.204.49@tcp [11837.359959] Key type .llcrypt unregistered [11837.362061] Key type ._llcrypt unregistered [11870.003491] Key type ._llcrypt registered [11870.015701] Key type .llcrypt registered [11870.628367] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11870.649403] alg: No test for adler32 (adler32-zlib) [11871.760869] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [11871.974584] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [11873.687559] Key type lgssc registered [11875.031741] Lustre: Echo OBD driver; http://www.lustre.org/ [11977.033079] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11984.431417] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12012.599914] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12058.885399] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12061.217634] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 05:54:17 (1788947657) [12100.126912] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12106.506848] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12130.140200] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12139.062870] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [12143.102882] Lustre: Unmounted lustre-client [12171.924092] Key type lgssc unregistered [12172.381372] LNet: 256555:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12172.406291] LNetError: 256555:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12172.445291] LNet: Removed LNI 192.168.204.49@tcp [12173.419125] Key type .llcrypt unregistered [12173.438337] Key type ._llcrypt unregistered [12194.375466] Key type ._llcrypt registered [12194.380591] Key type .llcrypt registered [12196.207909] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12198.414730] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 05:56:34 (1788947794) [12209.495400] Key type .llcrypt unregistered [12209.498034] Key type ._llcrypt unregistered [12218.633365] Key type ._llcrypt registered [12218.635897] Key type .llcrypt registered [12218.857405] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12218.875475] alg: No test for adler32 (adler32-zlib) [12220.184951] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [12220.429104] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [12222.199389] Key type lgssc registered [12223.643990] Lustre: Echo OBD driver; http://www.lustre.org/ [12286.615243] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12305.042667] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [12308.119029] Lustre: Unmounted lustre-client [12337.099749] Key type lgssc unregistered [12337.376842] LNet: 260004:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12337.399706] LNetError: 260004:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12337.447688] LNet: Removed LNI 192.168.204.49@tcp [12338.199439] Key type .llcrypt unregistered [12338.207399] Key type ._llcrypt unregistered [12354.866772] Key type ._llcrypt registered [12354.871246] Key type .llcrypt registered [12356.344813] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 05:59:12 (1788947952) [12358.818927] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12359.002095] alg: No test for adler32 (adler32-zlib) [12360.304387] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [12360.739675] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [12362.480239] Key type lgssc registered [12364.201549] Lustre: Echo OBD driver; http://www.lustre.org/ [12460.123765] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12496.473266] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12502.688892] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12505.638360] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [12508.058369] Lustre: Unmounted lustre-client [12538.066368] Key type lgssc unregistered [12538.415195] LNet: 263553:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12538.434579] LNetError: 263553:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12538.449275] LNet: Removed LNI 192.168.204.49@tcp [12539.311216] Key type .llcrypt unregistered [12539.317476] Key type ._llcrypt unregistered [12557.165265] Key type ._llcrypt registered [12557.167151] Key type .llcrypt registered [12558.891726] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 06:02:35 (1788948155) [12561.348574] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12561.381797] alg: No test for adler32 (adler32-zlib) [12562.561181] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [12562.810185] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [12564.543293] Key type lgssc registered [12565.867129] Lustre: Echo OBD driver; http://www.lustre.org/ [12621.761512] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12628.999848] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12653.851445] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12665.921664] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [12670.962374] Lustre: lustre-MDT0000-mdc-ffff95e886c67000: Connection to lustre-MDT0000 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [12677.446899] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12677.490981] LustreError: 266709:0:(file.c:6178:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12677.899232] LustreError: MGC192.168.204.149@tcp: Connection to MGS (at 192.168.204.149@tcp) was lost; in progress operations using this service will fail [12677.970495] Lustre: Unmounted lustre-client [12698.573459] Key type lgssc unregistered [12698.948201] LNet: 267122:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12698.962486] LNetError: 267122:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12698.993962] LNet: Removed LNI 192.168.204.49@tcp [12699.750338] Key type .llcrypt unregistered [12699.756614] Key type ._llcrypt unregistered [12715.499660] Key type ._llcrypt registered [12715.502350] Key type .llcrypt registered [12717.002852] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 06:05:13 (1788948313) [12719.263644] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12719.278779] alg: No test for adler32 (adler32-zlib) [12720.287704] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [12720.502704] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [12722.159552] Key type lgssc registered [12723.348539] Lustre: Echo OBD driver; http://www.lustre.org/ [12773.507466] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12779.022286] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12800.240917] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12801.907065] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [12807.146945] Lustre: lustre-MDT0001-mdc-ffff95e886c67000: Connection to lustre-MDT0001 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [12812.435582] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12815.106671] Lustre: Unmounted lustre-client [12836.035847] Key type lgssc unregistered [12836.319170] LNet: 270697:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12836.334493] LNetError: 270697:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12836.347373] LNet: Removed LNI 192.168.204.49@tcp [12837.085971] Key type .llcrypt unregistered [12837.089226] Key type ._llcrypt unregistered [12853.435213] Key type ._llcrypt registered [12853.437235] Key type .llcrypt registered [12854.704952] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 06:07:31 (1788948451) [12878.280468] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12878.356239] alg: No test for adler32 (adler32-zlib) [12879.566592] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [12879.985722] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [12881.679559] Key type lgssc registered [12883.538746] Lustre: Echo OBD driver; http://www.lustre.org/ [12991.706363] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12994.670185] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [13035.501227] Lustre: lustre-MDT0001-mdc-ffff95e8887a7800: Connection to lustre-MDT0001 (at 192.168.204.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [13043.904926] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13054.236778] Lustre: Unmounted lustre-client [13080.339264] Key type lgssc unregistered [13080.614076] LNet: 276474:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13080.630188] LNetError: 276474:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13080.653196] LNet: Removed LNI 192.168.204.49@tcp [13081.338510] Key type .llcrypt unregistered [13081.341578] Key type ._llcrypt unregistered [13098.041139] Key type ._llcrypt registered [13098.045566] Key type .llcrypt registered [13099.295836] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 06:11:36 (1788948696) [13100.925107] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13102.580851] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 06:11:39 (1788948699) [13103.896948] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13105.465807] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 06:11:42 (1788948702) [13106.995816] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13108.734255] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 06:11:45 (1788948705) [13110.234562] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13112.221217] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 06:11:49 (1788948709) [13113.705637] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13115.384856] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 06:11:52 (1788948712) [13124.314338] Key type .llcrypt unregistered [13124.319369] Key type ._llcrypt unregistered [13132.549494] Key type ._llcrypt registered [13132.553966] Key type .llcrypt registered [13133.003910] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13133.024387] alg: No test for adler32 (adler32-zlib) [13134.165556] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [13134.375545] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [13136.055246] Key type lgssc registered [13137.499330] Lustre: Echo OBD driver; http://www.lustre.org/ [13210.251840] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13215.576475] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13237.971958] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13239.758311] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [13243.328877] Lustre: Unmounted lustre-client [13281.808987] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 06:14:38 (1788948878) [13316.369774] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13322.171177] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13353.870754] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13364.779657] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [13367.723971] Lustre: Unmounted lustre-client [13411.871439] LNet: 1 local NIs in recovery (showing 1): 192.168.204.49@tcp [13412.652813] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 06:16:49 (1788949009) [14061.993499] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14067.987542] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14102.600632] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14205.848888] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 06:30:02 (1788949802) [14218.499842] Key type lgssc unregistered [14218.766845] LNet: 289998:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14218.773775] LNetError: 289998:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14218.796484] LNet: Removed LNI 192.168.204.49@tcp [14219.541649] Key type .llcrypt unregistered [14219.545097] Key type ._llcrypt unregistered [14354.465518] Key type ._llcrypt registered [14354.470532] Key type .llcrypt registered [14354.671678] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14354.691243] alg: No test for adler32 (adler32-zlib) [14356.168841] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [14356.623379] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [14358.423685] Key type lgssc registered [14360.767303] Lustre: Echo OBD driver; http://www.lustre.org/ [14465.540203] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14471.860841] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14498.527670] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14536.354836] Key type lgssc unregistered [14536.827934] LNet: 293694:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14536.848682] LNetError: 293694:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14536.874645] LNet: Removed LNI 192.168.204.49@tcp [14537.822895] Key type .llcrypt unregistered [14537.825551] Key type ._llcrypt unregistered [14573.802510] Key type ._llcrypt registered [14573.804112] Key type .llcrypt registered [14575.272239] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 06:36:11 (1788950171) [14599.196412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14599.323982] alg: No test for adler32 (adler32-zlib) [14600.377734] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [14600.693893] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [14602.383961] Key type lgssc registered [14603.438585] Lustre: Echo OBD driver; http://www.lustre.org/ [14664.389880] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 06:37:40 (1788950260) [14682.286770] Key type lgssc unregistered [14682.701272] LNet: 297248:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14682.710478] LNetError: 297248:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14682.725615] LNet: Removed LNI 192.168.204.49@tcp [14683.482635] Key type .llcrypt unregistered [14683.484876] Key type ._llcrypt unregistered [14695.759361] Key type ._llcrypt registered [14695.761017] Key type .llcrypt registered [14696.277592] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14696.303890] alg: No test for adler32 (adler32-zlib) [14697.544887] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [14697.830802] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [14699.495643] Key type lgssc registered [14700.933772] Lustre: Echo OBD driver; http://www.lustre.org/ [14759.656594] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14764.956543] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14788.569584] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15088.621496] LNet: Added LNI 192.168.204.49@tcp50 [8/256/0/180] [15090.926799] LNet: Removed LNI 192.168.204.49@tcp [15093.027806] Lustre: 301645:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff95e88534e800: config has no valid NIDs, force dynamic NIDs [15094.238039] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [15094.400286] Lustre: Unmounted lustre-client [15097.022827] Key type lgssc unregistered [15097.295778] LNet: 301903:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15097.303278] LNetError: 301903:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15097.325077] LNet: Removed LNI 192.168.204.49@tcp50 [15097.826507] Key type .llcrypt unregistered [15097.829560] Key type ._llcrypt unregistered [15115.122588] Key type ._llcrypt registered [15115.127457] Key type .llcrypt registered [15115.503932] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15115.513762] alg: No test for adler32 (adler32-zlib) [15116.580704] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [15116.831385] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [15118.585810] Key type lgssc registered [15119.774635] Lustre: Echo OBD driver; http://www.lustre.org/ [15181.265677] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15187.891705] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15196.598648] LNet: Added LNI 192.168.204.49@tcp50 [8/256/0/180] [15197.528923] LNet: Removed LNI 192.168.204.49@tcp [15198.692130] Lustre: 304591:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff95e886099000: config has no valid NIDs, force dynamic NIDs [15199.888292] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [15200.474301] Lustre: Unmounted lustre-client [15244.033328] Key type lgssc unregistered [15244.598932] LNet: 305460:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15244.615483] LNetError: 305460:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15244.636774] LNet: Removed LNI 192.168.204.49@tcp50 [15245.681230] Key type .llcrypt unregistered [15245.684681] Key type ._llcrypt unregistered [15260.840923] Key type ._llcrypt registered [15260.845891] Key type .llcrypt registered [15262.069513] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 06:47:38 (1788950858) [15279.650052] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15279.670032] alg: No test for adler32 (adler32-zlib) [15280.805565] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [15281.132371] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [15282.871905] Key type lgssc registered [15283.995867] Lustre: Echo OBD driver; http://www.lustre.org/ [15361.312930] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15366.998711] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15392.994185] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15425.678532] Key type lgssc unregistered [15425.941156] LNet: 310203:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15425.953753] LNetError: 310203:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15425.977845] LNet: Removed LNI 192.168.204.49@tcp [15426.581970] Key type .llcrypt unregistered [15426.588569] Key type ._llcrypt unregistered [15437.763832] Key type ._llcrypt registered [15437.768873] Key type .llcrypt registered [15439.214119] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 06:50:35 (1788951035) [15441.551317] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15441.577325] alg: No test for adler32 (adler32-zlib) [15442.624371] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [15442.854756] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [15444.567276] Key type lgssc registered [15445.971698] Lustre: Echo OBD driver; http://www.lustre.org/ [15496.299514] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15501.863523] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15524.693151] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15527.340375] Lustre: Mounted lustre-client - version 2.17.58_40_gec2f674 [15588.872918] Lustre: Mounted fs15246-client - version 2.17.58_40_gec2f674 [15602.885057] Lustre: Unmounted fs15246-client [15630.751930] Lustre: Unmounted lustre-client [15658.395216] Key type lgssc unregistered [15658.697629] LNet: 315056:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15658.705445] LNetError: 315056:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15658.720938] LNet: Removed LNI 192.168.204.49@tcp [15659.362309] Key type .llcrypt unregistered [15659.364599] Key type ._llcrypt unregistered [15670.409540] Key type ._llcrypt registered [15670.414939] Key type .llcrypt registered [15671.942862] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 06:54:28 (1788951268) [15674.008152] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15674.032741] alg: No test for adler32 (adler32-zlib) [15675.145945] Lustre: Lustre: Build Version: 2.17.58_40_gec2f674 [15675.440977] LNet: Added LNI 192.168.204.49@tcp [8/256/0/180] [15677.143475] Key type lgssc registered [15678.542479] Lustre: Echo OBD driver; http://www.lustre.org/ [15800.505837] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15805.859213] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15829.761442] Lustre: DEBUG MARKER: oleg449-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15922.667199] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15593 sec ========= 06:58:39 (1788951519) [15924.576573] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 06:58:41 (1788951521) === [15928.264382] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 06:58:44 (1788951524) === [15962.800515] Key type lgssc unregistered [15963.057985] LNet: 324312:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15963.073569] LNetError: 324312:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15963.091055] LNet: Removed LNI 192.168.204.49@tcp [15963.928222] Key type .llcrypt unregistered [15963.932821] Key type ._llcrypt unregistered