[ 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 393986369 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, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.002365] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004010] kvm-guest: setup PV IPIs [ 0.007444] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009008] pid_max: default: 32768 minimum: 301 [ 0.010112] LSM: Security Framework initializing [ 0.011044] Yama: becoming mindful. [ 0.012033] SELinux: Initializing. [ 0.013048] *** VALIDATE selinux *** [ 0.021179] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025366] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026136] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027105] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028141] *** VALIDATE tmpfs *** [ 0.029427] *** VALIDATE proc *** [ 0.030199] *** VALIDATE cgroup *** [ 0.031007] *** VALIDATE cgroup2 *** [ 0.032230] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.033126] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.034004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.035035] Spectre V2 : User space: Vulnerable [ 0.036009] Speculative Store Bypass: Vulnerable [ 0.039032] debug: unmapping init [mem 0xffffffffae859000-0xffffffffae860fff] [ 0.041153] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.042688] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.043021] ... version: 2 [ 0.044016] ... bit width: 48 [ 0.045013] ... generic registers: 4 [ 0.046010] ... value mask: 0000ffffffffffff [ 0.047012] ... max period: 00007fffffffffff [ 0.048007] ... fixed-purpose events: 3 [ 0.049007] ... event mask: 000000070000000f [ 0.051162] rcu: Hierarchical SRCU implementation. [ 0.053063] smp: Bringing up secondary CPUs ... [ 0.054568] x86: Booting SMP configuration: [ 0.055017] .... node #0, CPUs: #1 #2 #3 [ 0.057634] smp: Brought up 1 node, 4 CPUs [ 0.059007] smpboot: Max logical packages: 1 [ 0.060010] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.228033] node 0 deferred pages initialised in 166ms [ 0.232172] devtmpfs: initialized [ 0.233261] x86/mm: Memory block size: 128MB [ 0.235980] gcov: version magic: 0x41383552 [ 0.237324] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.238095] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.239302] pinctrl core: initialized pinctrl subsystem [ 0.240205] [ 0.240725] ************************************************************* [ 0.241015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.242011] ** ** [ 0.243012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.244011] ** ** [ 0.245011] ** This means that this kernel is built to expose internal ** [ 0.246012] ** IOMMU data structures, which may compromise security on ** [ 0.247011] ** your system. ** [ 0.248009] ** ** [ 0.249008] ** If you see this message and you are not debugging the ** [ 0.250007] ** kernel, report this immediately to your vendor! ** [ 0.251011] ** ** [ 0.252009] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.253006] ************************************************************* [ 0.254616] NET: Registered protocol family 16 [ 0.255331] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.256036] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.257038] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.258413] cpuidle: using governor menu [ 0.260737] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.262501] PCI: Using configuration type 1 for base access [ 0.264107] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.272141] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.273019] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.274217] cryptd: max_cpu_qlen set to 1000 [ 0.276277] ACPI: Added _OSI(Module Device) [ 0.277012] ACPI: Added _OSI(Processor Device) [ 0.279009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.281012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.287119] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.293339] ACPI: Interpreter enabled [ 0.294059] ACPI: PM: (supports S0 S3 S4 S5) [ 0.296011] ACPI: Using IOAPIC for interrupt routing [ 0.297125] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.302000] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.313242] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.315030] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.318020] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.321097] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.326349] acpiphp: Slot [2] registered [ 0.328081] acpiphp: Slot [5] registered [ 0.329086] acpiphp: Slot [6] registered [ 0.331145] acpiphp: Slot [3] registered [ 0.332114] acpiphp: Slot [4] registered [ 0.333085] acpiphp: Slot [7] registered [ 0.335079] acpiphp: Slot [8] registered [ 0.337098] acpiphp: Slot [9] registered [ 0.338098] acpiphp: Slot [10] registered [ 0.340107] acpiphp: Slot [11] registered [ 0.342074] acpiphp: Slot [12] registered [ 0.344112] acpiphp: Slot [13] registered [ 0.345128] acpiphp: Slot [14] registered [ 0.347086] acpiphp: Slot [15] registered [ 0.348060] acpiphp: Slot [16] registered [ 0.350108] acpiphp: Slot [17] registered [ 0.351069] acpiphp: Slot [18] registered [ 0.352076] acpiphp: Slot [19] registered [ 0.353084] acpiphp: Slot [20] registered [ 0.355086] acpiphp: Slot [21] registered [ 0.356047] acpiphp: Slot [22] registered [ 0.357062] acpiphp: Slot [23] registered [ 0.358058] acpiphp: Slot [24] registered [ 0.359098] acpiphp: Slot [25] registered [ 0.361081] acpiphp: Slot [26] registered [ 0.363085] acpiphp: Slot [27] registered [ 0.364107] acpiphp: Slot [28] registered [ 0.366081] acpiphp: Slot [29] registered [ 0.367067] acpiphp: Slot [30] registered [ 0.369102] acpiphp: Slot [31] registered [ 0.371087] PCI host bridge to bus 0000:00 [ 0.372017] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.374024] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.376014] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.378017] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.381023] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.383023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.385164] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.387763] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.390129] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.398013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.400483] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.403016] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.405019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.408022] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.410611] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.412695] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.417056] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.419782] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.423817] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.431017] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.435012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.439927] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.446021] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.451018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.462012] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.470751] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.473815] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.478016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.488011] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.496271] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.498296] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.501399] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.503556] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.506233] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.510142] iommu: Default domain type: Passthrough [ 0.513420] SCSI subsystem initialized [ 0.514121] ACPI: bus type USB registered [ 0.516112] usbcore: registered new interface driver usbfs [ 0.518052] usbcore: registered new interface driver hub [ 0.519065] usbcore: registered new device driver usb [ 0.521113] pps_core: LinuxPPS API ver. 1 registered [ 0.522005] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.524051] PTP clock support registered [ 0.526114] EDAC MC: Ver: 3.0.0 [ 0.528137] PCI: Using ACPI for IRQ routing [ 0.529458] NetLabel: Initializing [ 0.530009] NetLabel: domain hash size = 128 [ 0.532009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.533072] NetLabel: unlabeled traffic allowed by default [ 0.535134] vgaarb: loaded [ 0.536256] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.538011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.544297] clocksource: Switched to clocksource kvm-clock [ 0.646969] VFS: Disk quotas dquot_6.6.0 [ 0.648046] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.650047] *** VALIDATE ramfs *** [ 0.651015] *** VALIDATE hugetlbfs *** [ 0.652250] pnp: PnP ACPI init [ 0.653976] pnp: PnP ACPI: found 6 devices [ 0.667820] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.671112] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.673444] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.675577] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.678921] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.681190] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.684346] NET: Registered protocol family 2 [ 0.686589] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.691154] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.694944] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.699857] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.702713] TCP: Hash tables configured (established 65536 bind 65536) [ 0.705427] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.707901] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.710545] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.712995] NET: Registered protocol family 1 [ 0.715064] RPC: Registered named UNIX socket transport module. [ 0.716898] RPC: Registered udp transport module. [ 0.718223] RPC: Registered tcp transport module. [ 0.719548] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.721779] NET: Registered protocol family 44 [ 0.724027] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.725933] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.728110] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.730012] PCI: CLS 0 bytes, default 64 [ 0.731142] Unpacking initramfs... [ 2.089173] debug: unmapping init [mem 0xffff9a667cc64000-0xffff9a667ffcffff] [ 2.094963] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.097168] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.099666] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.617668] Initialise system trusted keyrings [ 2.624512] Key type blacklist registered [ 2.629362] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.646174] zbud: loaded [ 2.649478] *** VALIDATE nfs *** [ 2.650652] *** VALIDATE nfs4 *** [ 2.656124] pstore: using deflate compression [ 2.660545] Platform Keyring initialized [ 2.792600] NET: Registered protocol family 38 [ 2.795339] Key type asymmetric registered [ 2.798849] Asymmetric key parser 'x509' registered [ 2.802851] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.807327] io scheduler mq-deadline registered [ 2.809677] io scheduler kyber registered [ 2.812156] io scheduler bfq registered [ 2.815595] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.820247] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.827135] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.831415] ACPI: Power Button [PWRF] [ 2.836717] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.847532] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.862781] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.899943] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.928335] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.933469] Non-volatile memory driver v1.3 [ 2.935449] Linux agpgart interface v0.103 [ 2.971140] virtio_blk virtio1: [vda] 146144 512-byte logical blocks (74.8 MB/71.4 MiB) [ 2.974896] vda: detected capacity change from 0 to 74825728 [ 2.994311] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.997428] vdb: detected capacity change from 0 to 1073741824 [ 3.017610] libphy: Fixed MDIO Bus: probed [ 3.026705] usbcore: registered new interface driver usbserial_generic [ 3.035389] usbserial: USB Serial support registered for generic [ 3.037959] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.043478] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.045673] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.048890] mousedev: PS/2 mouse device common for all mice [ 3.053634] rtc_cmos 00:05: RTC can wake from S4 [ 3.057591] rtc_cmos 00:05: registered as rtc0 [ 3.059406] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.062592] intel_pstate: CPU model not supported [ 3.066376] hid: raw HID events driver (C) Jiri Kosina [ 3.068647] usbcore: registered new interface driver usbhid [ 3.071063] usbhid: USB HID core driver [ 3.072767] drop_monitor: Initializing network drop monitor service [ 3.074522] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.077389] Initializing XFRM netlink socket [ 3.086764] NET: Registered protocol family 10 [ 3.089726] Segment Routing with IPv6 [ 3.092161] NET: Registered protocol family 17 [ 3.094538] mpls_gso: MPLS GSO support [ 3.096030] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.103889] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.109793] RAS: Correctable Errors collector initialized. [ 3.111825] AVX version of gcm_enc/dec engaged. [ 3.113568] AES CTR mode by8 optimization enabled [ 3.193253] sched_clock: Marking stable (3193230647, 0)->(4051198597, -857967950) [ 3.196927] registered taskstats version 1 [ 3.198974] Loading compiled-in X.509 certificates [ 3.201311] zswap: loaded using pool lzo/zbud [ 3.230964] Key type big_key registered [ 3.246318] Key type encrypted registered [ 3.248530] ima: No TPM chip found, activating TPM-bypass! [ 3.251372] ima: Allocated hash algorithm: sha1 [ 3.253451] ima: No architecture policies found [ 3.255703] evm: Initialising EVM extended attributes: [ 3.258232] evm: security.selinux [ 3.260411] evm: security.ima [ 3.261914] evm: security.capability [ 3.263654] evm: HMAC attrs: 0x1 [ 3.266739] rtc_cmos 00:05: setting system clock to 2026-09-01 14:03:53 UTC (1788271433) [ 3.275952] debug: unmapping init [mem 0xffffffffaf803000-0xffffffffaf9fffff] [ 3.279959] debug: unmapping init [mem 0xffffffffae582000-0xffffffffae858fff] [ 3.292275] Write protecting the kernel read-only data: 28672k [ 3.299473] debug: unmapping init [mem 0xffffffffacc03000-0xffffffffacdfffff] [ 3.305345] debug: unmapping init [mem 0xffffffffad514000-0xffffffffad5fffff] [ 3.344189] 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.358665] systemd[1]: Detected virtualization kvm. [ 3.361579] systemd[1]: Detected architecture x86-64. [ 3.364338] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.395136] systemd[1]: No hostname configured. [ 3.398558] systemd[1]: Set hostname to . [ 3.401925] random: systemd: uninitialized urandom read (16 bytes read) [ 3.405226] systemd[1]: Initializing machine ID from random generator. [ 3.483423] random: ln: uninitialized urandom read (6 bytes read) [ 3.607224] random: systemd: uninitialized urandom read (16 bytes read) [ 3.610887] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.622512] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.634704] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... Starting Journal Service... Starting Setup Virtual Console... [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.477632] device-mapper: uevent: version 1.0.3 [ 4.480660] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ 5.445799] virtio_net virtio0 ens2: renamed from eth0 [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.488885] scsi host0: ata_piix [ 5.497326] scsi host1: ata_piix [ 5.498851] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.501463] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.875543] random: fast init done [ 10.781224] random: crng init done [ 10.789645] random: 7 urandom warning(s) missed due to ratelimiting [ 10.876847] hrtimer: interrupt took 6025882 ns [ 11.091457] dracut-initqueue[584]: 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... [ 13.426356] 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 Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] 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... [ 15.343431] printk: systemd: 25 output lines suppressed due to ratelimiting [ 15.739243] SELinux: Disabled at runtime. [ 15.818717] 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.825997] systemd[1]: Detected virtualization kvm. [ 15.827319] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 17.010745] systemd[1]: initrd-switch-root.service: Succeeded. [ 17.024620] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 17.043363] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 17.051022] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 17.055325] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 17.068565] systemd[1]: Starting Journal Service... Starting Journal Service... [ 17.076436] systemd[1]: Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Switch Root. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on udev Kernel Socket. [ OK ] Stopped target Initrd File Systems. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Remount Root and Kernel File Systems... Starting Apply Kernel Variables... Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Stopped target Initrd Root File System. [ 17.292849] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice User and Session Slice. Mounting Kernel Debug File System... [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-serial\x2dgetty.slice. Starting udev Coldplug all Devices... [ OK ] Created slice system-getty.slice. Mounting Huge Pages File System... Mounting POSIX Message Queue File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Slices. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 18.042723] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 19.097402] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 19.613569] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 19.865858] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 20.138125] EDAC sbridge: Ver: 1.1.2 [ 22.334154] Key type dns_resolver registered [ 22.934738] NFS: Registering the id_resolver key type [ 22.937769] Key type id_resolver registered [ 22.939823] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started RPC Bind. [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ 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. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg354-client login: [ 63.518243] libcfs: loading out-of-tree module taints kernel. [ 63.614127] Key type ._llcrypt registered [ 63.625604] Key type .llcrypt registered [ 63.882509] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 63.894203] alg: No test for adler32 (adler32-zlib) [ 65.081455] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 65.509685] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 67.135215] Key type lgssc registered [ 67.890964] Lustre: Echo OBD driver; http://www.lustre.org/ [ 208.123280] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 212.327403] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 222.484295] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing check_logdir /tmp/testlogs/ [ 226.807973] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing yml_node [ 230.332935] Lustre: DEBUG MARKER: Client: 2.17.57.64 [ 232.515805] Lustre: DEBUG MARKER: MDS: 2.17.57.64 [ 233.955784] Lustre: lustre-OST0000-osc-ffff9a66c68b6000: disconnect after 24s idle [ 234.433966] Lustre: DEBUG MARKER: OSS: 2.17.57.64 [ 236.012826] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Sep 1 10:07:44 EDT 2026 [ 249.351168] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 250.588768] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 252.499747] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 262.804289] Lustre: Unmounted lustre-client [ 364.614330] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 369.855234] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 394.592559] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 428.634571] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 10:10:57 (1788271857) [ 430.874571] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 432.643623] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 10:11:01 (1788271861) [ 462.118251] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 467.041884] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 485.906179] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 486.512033] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 491.036811] Lustre: Unmounted lustre-client [ 517.889228] Key type lgssc unregistered [ 518.269587] LNet: 15023:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 518.279533] LNetError: 15023:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 518.303355] LNet: Removed LNI 192.168.203.54@tcp [ 519.211291] Key type .llcrypt unregistered [ 519.216183] Key type ._llcrypt unregistered [ 534.153810] Key type ._llcrypt registered [ 534.156900] Key type .llcrypt registered [ 535.382791] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 10:12:44 (1788271964) [ 536.864810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 536.916163] alg: No test for adler32 (adler32-zlib) [ 538.051471] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 538.358841] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 539.991198] Key type lgssc registered [ 541.167230] Lustre: Echo OBD driver; http://www.lustre.org/ [ 608.769271] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 692.446256] Lustre: Unmounted lustre-client [ 717.251149] Key type lgssc unregistered [ 717.480400] LNet: 18858:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 717.490314] LNetError: 18858:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 717.508274] LNet: Removed LNI 192.168.203.54@tcp [ 718.050443] Key type .llcrypt unregistered [ 718.052947] Key type ._llcrypt unregistered [ 725.845990] Key type ._llcrypt registered [ 725.850113] Key type .llcrypt registered [ 726.941470] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 10:15:55 (1788272155) [ 729.012641] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 729.055955] alg: No test for adler32 (adler32-zlib) [ 730.142806] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 730.338747] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 731.991260] Key type lgssc registered [ 732.799800] Lustre: Echo OBD driver; http://www.lustre.org/ [ 769.018179] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 772.768363] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 786.059496] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 796.727477] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 805.061643] Lustre: Unmounted lustre-client [ 836.012729] Key type lgssc unregistered [ 836.240741] LNet: 22453:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 836.254829] LNetError: 22453:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 836.279838] LNet: Removed LNI 192.168.203.54@tcp [ 836.784180] Key type .llcrypt unregistered [ 836.788351] Key type ._llcrypt unregistered [ 844.013730] Key type ._llcrypt registered [ 844.015591] Key type .llcrypt registered [ 844.879937] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 10:17:54 (1788272274) [ 846.460945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 846.506083] alg: No test for adler32 (adler32-zlib) [ 847.510924] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 847.744268] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 849.383211] Key type lgssc registered [ 850.266824] Lustre: Echo OBD driver; http://www.lustre.org/ [ 913.307336] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 919.066408] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 945.104104] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 948.131193] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 956.036154] Lustre: Unmounted lustre-client [ 986.636231] Key type lgssc unregistered [ 986.981318] LNet: 25652:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 986.987479] LNetError: 25652:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 987.011476] LNet: Removed LNI 192.168.203.54@tcp [ 987.848547] Key type .llcrypt unregistered [ 987.851457] Key type ._llcrypt unregistered [ 1007.713517] Key type ._llcrypt registered [ 1007.720454] Key type .llcrypt registered [ 1009.601495] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 10:20:37 (1788272437) [ 1012.902822] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1012.932389] alg: No test for adler32 (adler32-zlib) [ 1014.111632] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 1014.512944] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 1016.280088] Key type lgssc registered [ 1017.585732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1069.825563] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1075.919143] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1098.142278] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1100.879968] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 1111.023897] Lustre: lustre-MDT0000-mdc-ffff9a66c64dc800: Connection to lustre-MDT0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1121.262709] Lustre: lustre-MDT0001-mdc-ffff9a66c64dc800: Connection to lustre-MDT0001 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1127.228379] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1132.511218] Lustre: 26631:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788272546/real 1788272546] req@0000000057a6fc6e x1875139566246016/t0(0) o400->MGC192.168.203.154@tcp@192.168.203.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1788272562 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1132.560764] LustreError: MGC192.168.203.154@tcp: Connection to MGS (at 192.168.203.154@tcp) was lost; in progress operations using this service will fail [ 1137.576279] Lustre: Unmounted lustre-client [ 1150.988928] Key type lgssc unregistered [ 1151.259721] LNet: 29333:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1151.264622] LNetError: 29333:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1151.289180] LNet: Removed LNI 192.168.203.54@tcp [ 1151.990602] Key type .llcrypt unregistered [ 1151.997211] Key type ._llcrypt unregistered [ 1168.197261] Key type ._llcrypt registered [ 1168.201519] Key type .llcrypt registered [ 1169.556763] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 10:23:18 (1788272598) [ 1171.830671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1171.853474] alg: No test for adler32 (adler32-zlib) [ 1172.902612] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 1173.234662] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 1174.943210] Key type lgssc registered [ 1176.235178] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1226.981087] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1232.981594] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1254.098575] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1255.622747] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 1266.154307] Lustre: lustre-OST0000-osc-ffff9a66e0059000: Connection to lustre-OST0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1271.608693] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1281.969832] Lustre: Unmounted lustre-client [ 1310.179959] Key type lgssc unregistered [ 1310.421545] LNet: 32949:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1310.443306] LNetError: 32949:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1310.465650] LNet: Removed LNI 192.168.203.54@tcp [ 1311.076864] Key type .llcrypt unregistered [ 1311.079868] Key type ._llcrypt unregistered [ 1325.746609] Key type ._llcrypt registered [ 1325.748804] Key type .llcrypt registered [ 1326.874374] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 10:25:55 (1788272755) [ 1329.307839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1329.454667] alg: No test for adler32 (adler32-zlib) [ 1330.563643] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 1330.906850] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 1332.655252] Key type lgssc registered [ 1333.824762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1383.103398] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1388.273780] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1407.095607] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1409.861964] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 1413.219136] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1416.865756] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1428.794850] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-0110h27m37s [ 1431.104531] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1435.626255] Lustre: lustre-MDT0000-mdc-ffff9a66c8daf800: Connection to lustre-MDT0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1441.593486] LustreError: 36738:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9a66c8daf800: can't stat MDS #0: rc = -19 [ 1442.921260] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1453.037346] LustreError: MGC192.168.203.154@tcp: Connection to MGS (at 192.168.203.154@tcp) was lost; in progress operations using this service will fail [ 1453.063503] Lustre: Evicted from MGS (at 192.168.203.154@tcp) after server handle changed from 0x517e4e36869d064b to 0x517e4e36869d090e [ 1453.075473] Lustre: MGC192.168.203.154@tcp: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 1454.118586] LustreError: lustre-MDT0000-mdc-ffff9a66c8daf800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1454.146912] Lustre: lustre-MDT0000-mdc-ffff9a66c8daf800: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 1459.416292] Lustre: DEBUG MARKER: Wait for df (36738) ... [ 1461.020951] Lustre: DEBUG MARKER: done [ 1463.216364] Lustre: Unmounted lustre-client [ 1499.323834] Key type lgssc unregistered [ 1499.613507] LNet: 37758:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1499.625116] LNetError: 37758:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1499.652979] LNet: Removed LNI 192.168.203.54@tcp [ 1500.245144] Key type .llcrypt unregistered [ 1500.248232] Key type ._llcrypt unregistered [ 1527.888607] Key type ._llcrypt registered [ 1527.890458] Key type .llcrypt registered [ 1529.161222] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 10:29:17 (1788272957) [ 1531.778434] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1531.790837] alg: No test for adler32 (adler32-zlib) [ 1532.791745] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 1533.028266] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 1534.687276] Key type lgssc registered [ 1536.048375] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1589.172908] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1595.851795] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1619.962078] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1621.715156] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 1626.753615] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-0110h30m55s [ 1628.281196] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1635.841549] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1638.491423] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1659.359194] Lustre: 41913:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788273069/real 1788273069] req@000000002a7400b4 x1875140110457472/t0(0) o101->lustre-MDT0000-mdc-ffff9a66c8dcb800@192.168.203.154@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788273089 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1659.395423] Lustre: lustre-MDT0000-mdc-ffff9a66c8dcb800: Connection to lustre-MDT0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1659.438518] Lustre: lustre-MDT0000-mdc-ffff9a66c8dcb800: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 1680.770883] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1683.521976] Lustre: DEBUG MARKER: done [ 1687.839222] Lustre: Unmounted lustre-client [ 1724.629878] Key type lgssc unregistered [ 1724.944048] LNet: 42794:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1724.957317] LNetError: 42794:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1724.980397] LNet: Removed LNI 192.168.203.54@tcp [ 1725.839562] Key type .llcrypt unregistered [ 1725.844687] Key type ._llcrypt unregistered [ 1758.532961] Key type ._llcrypt registered [ 1758.539857] Key type .llcrypt registered [ 1760.009286] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 10:33:08 (1788273188) [ 1761.524821] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1763.288173] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 10:33:12 (1788273192) [ 1767.331131] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1767.384918] alg: No test for adler32 (adler32-zlib) [ 1768.465384] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 1768.801296] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 1770.527649] Key type lgssc registered [ 1771.875973] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1799.076573] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 10:33:48 (1788273228) [ 1831.586288] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1837.748752] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1857.581169] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1860.202778] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 1863.670477] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1866.903678] Lustre: Unmounted lustre-client [ 1883.501700] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1928.433537] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1934.112222] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1934.686360] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 1938.597364] Lustre: Unmounted lustre-client [ 1998.860307] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2003.238313] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2004.086508] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 2007.935204] Lustre: Unmounted lustre-client [ 2030.082595] Lustre: DEBUG MARKER: files compared the same [ 2052.030658] Key type lgssc unregistered [ 2052.272807] LNet: 50414:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2052.277129] LNetError: 50414:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2052.293336] LNet: Removed LNI 192.168.203.54@tcp [ 2052.936713] Key type .llcrypt unregistered [ 2052.940767] Key type ._llcrypt unregistered [ 2066.930499] Key type ._llcrypt registered [ 2066.933658] Key type .llcrypt registered [ 2068.473752] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 10:38:16 (1788273496) [ 2071.130561] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2071.175865] alg: No test for adler32 (adler32-zlib) [ 2072.264860] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 2072.544520] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 2074.265184] Key type lgssc registered [ 2075.348544] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2117.643643] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2123.159819] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2140.858803] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2146.869109] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 2150.452544] Lustre: Unmounted lustre-client [ 2177.821618] Key type lgssc unregistered [ 2178.028218] LNet: 53998:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2178.038967] LNetError: 53998:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2178.055915] LNet: Removed LNI 192.168.203.54@tcp [ 2178.684303] Key type .llcrypt unregistered [ 2178.686714] Key type ._llcrypt unregistered [ 2192.550393] Key type ._llcrypt registered [ 2192.554226] Key type .llcrypt registered [ 2193.635960] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 10:40:22 (1788273622) [ 2195.517453] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2195.533680] alg: No test for adler32 (adler32-zlib) [ 2196.573050] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 2196.831949] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 2198.487224] Key type lgssc registered [ 2199.433609] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2276.623046] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2302.092567] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2307.079171] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2330.735789] Key type lgssc unregistered [ 2330.938661] LNet: 57479:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2330.948867] LNetError: 57479:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2330.962939] LNet: Removed LNI 192.168.203.54@tcp [ 2331.475768] Key type .llcrypt unregistered [ 2331.478682] Key type ._llcrypt unregistered [ 2343.848865] Key type ._llcrypt registered [ 2343.851538] Key type .llcrypt registered [ 2344.846415] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 10:42:53 (1788273773) [ 2347.921273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2347.936450] alg: No test for adler32 (adler32-zlib) [ 2349.016400] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 2349.260941] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 2350.959188] Key type lgssc registered [ 2351.873763] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2405.761682] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 2412.412123] Lustre: Unmounted lustre-client [ 2442.944763] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 10:44:31 (1788273871) [ 2528.393905] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2544.684956] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 2551.043899] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2551.051318] LustreError: 63941:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9a66c68b1000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2551.070056] Lustre: 58463:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.154@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2561.358318] Lustre: Unmounted lustre-client [ 2595.560862] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 10:47:04 (1788274024) [ 2602.823497] Key type lgssc unregistered [ 2602.974701] LNet: 65155:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2602.979716] LNetError: 65155:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2602.991942] LNet: Removed LNI 192.168.203.54@tcp [ 2603.410617] Key type .llcrypt unregistered [ 2603.413443] Key type ._llcrypt unregistered [ 2611.487793] Key type ._llcrypt registered [ 2611.493223] Key type .llcrypt registered [ 2611.669889] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2611.682335] alg: No test for adler32 (adler32-zlib) [ 2612.709319] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 2612.914709] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 2614.551187] Key type lgssc registered [ 2615.307726] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2646.617492] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2650.224917] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2663.609183] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2664.957042] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 2667.473981] Lustre: Unmounted lustre-client [ 2680.556244] Key type lgssc unregistered [ 2680.873736] LNet: 68171:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2680.877910] LNetError: 68171:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2680.891145] LNet: Removed LNI 192.168.203.54@tcp [ 2681.502035] Key type .llcrypt unregistered [ 2681.511174] Key type ._llcrypt unregistered [ 2701.657510] Key type ._llcrypt registered [ 2701.678406] Key type .llcrypt registered [ 2702.020235] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2702.074607] alg: No test for adler32 (adler32-zlib) [ 2703.436636] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 2703.640943] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 2705.305408] Key type lgssc registered [ 2707.172348] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2818.332762] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2823.468433] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2842.067787] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2843.703877] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 2846.498575] Lustre: Unmounted lustre-client [ 2871.677611] Key type lgssc unregistered [ 2871.928967] LNet: 71609:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2871.932862] LNetError: 71609:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2871.951729] LNet: Removed LNI 192.168.203.54@tcp [ 2872.575226] Key type .llcrypt unregistered [ 2872.579107] Key type ._llcrypt unregistered [ 2885.625792] Key type ._llcrypt registered [ 2885.628166] Key type .llcrypt registered [ 2886.821367] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 10:51:55 (1788274315) [ 2888.766613] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2888.880989] alg: No test for adler32 (adler32-zlib) [ 2889.950588] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 2890.148610] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 2891.791296] Key type lgssc registered [ 2892.819441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2938.348906] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2944.286486] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2965.254603] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2967.986970] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 2976.877930] Lustre: Unmounted lustre-client [ 2977.340615] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 2980.905269] Lustre: Unmounted lustre-client [ 3009.097688] Key type lgssc unregistered [ 3009.500339] LNet: 75299:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3009.514692] LNetError: 75299:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3009.536777] LNet: Removed LNI 192.168.203.54@tcp [ 3010.338340] Key type .llcrypt unregistered [ 3010.344461] Key type ._llcrypt unregistered [ 3025.987173] Key type ._llcrypt registered [ 3025.989892] Key type .llcrypt registered [ 3026.571727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3026.596088] alg: No test for adler32 (adler32-zlib) [ 3027.655036] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 3027.953929] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 3029.703308] Key type lgssc registered [ 3030.911279] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3085.032992] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3090.672095] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3112.811553] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3115.041384] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 3123.317053] Lustre: Unmounted lustre-client [ 3152.453963] Key type lgssc unregistered [ 3152.709318] LNet: 78338:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3152.723270] LNetError: 78338:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3152.745908] LNet: Removed LNI 192.168.203.54@tcp [ 3153.394399] Key type .llcrypt unregistered [ 3153.397096] Key type ._llcrypt unregistered [ 3169.390840] Key type ._llcrypt registered [ 3169.397187] Key type .llcrypt registered [ 3171.183894] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 10:56:39 (1788274599) [ 3173.243355] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3173.297389] alg: No test for adler32 (adler32-zlib) [ 3174.376372] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 3174.798618] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 3176.583280] Key type lgssc registered [ 3177.663971] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3227.717230] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3232.674705] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3251.523580] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3254.115964] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 3261.225355] Lustre: lustre: root_squash is set to 0:0 [ 3271.262831] Lustre: lustre: nosquash_nids is cleared [ 3271.292486] Lustre: lustre: root_squash is set to 500:500 [ 3280.261986] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.54@tcp 192.168.0.[2,10]@tcp [ 3295.808686] Lustre: lustre: nosquash_nids set to 192.168.203.54/24@tcp [ 3302.847280] Lustre: Unmounted lustre-client [ 3339.326379] Key type lgssc unregistered [ 3339.580429] LNet: 82328:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3339.589395] LNetError: 82328:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3339.643328] LNet: Removed LNI 192.168.203.54@tcp [ 3340.394754] Key type .llcrypt unregistered [ 3340.398578] Key type ._llcrypt unregistered [ 3362.046193] Key type ._llcrypt registered [ 3362.047995] Key type .llcrypt registered [ 3364.232937] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 10:59:51 (1788274791) [ 3366.819672] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3368.986564] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 10:59:57 (1788274797) [ 3372.783305] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3372.839095] alg: No test for adler32 (adler32-zlib) [ 3373.969071] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 3374.360312] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 3376.063226] Key type lgssc registered [ 3377.732989] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3427.717788] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3433.541991] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3453.025185] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3453.548873] Lustre: lustre: root_squash is set to 500:500 [ 3453.566702] Lustre: lustre: nosquash_nids set to 192.168.203.54/24@tcp [ 3454.685768] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 3460.660491] Lustre: Unmounted lustre-client [ 3489.392608] Key type lgssc unregistered [ 3489.691480] LNet: 86308:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3489.696944] LNetError: 86308:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3489.716706] LNet: Removed LNI 192.168.203.54@tcp [ 3490.331856] Key type .llcrypt unregistered [ 3490.333919] Key type ._llcrypt unregistered [ 3504.659568] Key type ._llcrypt registered [ 3504.662435] Key type .llcrypt registered [ 3505.880420] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3507.312540] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 11:02:16 (1788274936) [ 3524.356790] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3524.461831] alg: No test for adler32 (adler32-zlib) [ 3525.638353] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 3526.096530] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 3527.831435] Key type lgssc registered [ 3529.296217] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3620.026957] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3625.344856] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3651.271781] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3718.268864] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3723.611890] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3748.703597] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3761.014862] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 3789.998330] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9a66c8dd8000.ost_server_uuid 50 [ 3791.581704] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9a66c8dd8000.ost_server_uuid in IDLE state after 0 sec [ 3792.007161] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 3795.173280] Lustre: Unmounted lustre-client [ 3797.019334] Lustre: Unmounted lustre-client [ 3849.279304] Key type lgssc unregistered [ 3849.566146] LNet: 93975:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3849.586563] LNetError: 93975:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3850.692088] LNet: Removed LNI 192.168.203.54@tcp [ 3851.462522] Key type .llcrypt unregistered [ 3851.464346] Key type ._llcrypt unregistered [ 3884.041077] Key type ._llcrypt registered [ 3884.043478] Key type .llcrypt registered [ 3885.245868] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 11:08:34 (1788275314) [ 3900.395079] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3900.411412] alg: No test for adler32 (adler32-zlib) [ 3901.476957] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 3901.702887] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 3903.383477] Key type lgssc registered [ 3904.413489] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3986.738734] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3993.940226] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4017.805205] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4020.573268] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 4042.207825] Lustre: 95339:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788275456/real 1788275456] req@000000009326edf4 x1875142593484032/t0(0) o400->lustre-OST0000-osc-ffff9a66c615e000@192.168.203.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1788275472 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4042.268812] Lustre: lustre-OST0000-osc-ffff9a66c615e000: Connection to lustre-OST0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4048.356088] Lustre: 95339:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788275461/real 1788275461] req@0000000043b59140 x1875142593484544/t0(0) o400->lustre-OST0000-osc-ffff9a66c615e000@192.168.203.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1788275477 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4052.512160] Lustre: lustre-OST0000-osc-ffff9a66c615e000: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 4053.599238] Lustre: 95339:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788275466/real 1788275466] req@00000000465c1a1a x1875142593485056/t0(0) o400->lustre-OST0000-osc-ffff9a66c615e000@192.168.203.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1788275482 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4057.058933] Lustre: 95338:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788275471/real 1788275471] req@00000000b9fd92b9 x1875142593485568/t0(0) o400->lustre-OST0000-osc-ffff9a66c615e000@192.168.203.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1788275487 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4057.578609] Lustre: lustre-MDT0000-mdc-ffff9a66c615e000: Connection to lustre-MDT0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4072.927209] Lustre: 95338:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788275487/real 1788275487] req@00000000f5383a34 x1875142593487872/t0(0) o400->MGC192.168.203.154@tcp@192.168.203.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1788275503 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4072.968302] LustreError: MGC192.168.203.154@tcp: Connection to MGS (at 192.168.203.154@tcp) was lost; in progress operations using this service will fail [ 4079.124262] Lustre: lustre-MDT0000-mdc-ffff9a66c615e000: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 4083.245216] Lustre: Evicted from MGS (at 192.168.203.154@tcp) after server handle changed from 0xddbb69aa38b8c4c0 to 0xddbb69aa38b8c8da [ 4083.288672] Lustre: MGC192.168.203.154@tcp: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 4089.430632] Lustre: Unmounted lustre-client [ 4128.560357] Key type lgssc unregistered [ 4128.836062] LNet: 99640:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4128.849787] LNetError: 99640:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4128.874477] LNet: Removed LNI 192.168.203.54@tcp [ 4129.665366] Key type .llcrypt unregistered [ 4129.668335] Key type ._llcrypt unregistered [ 4144.949564] Key type ._llcrypt registered [ 4144.953662] Key type .llcrypt registered [ 4146.417922] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 11:12:55 (1788275575) [ 4161.735754] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4161.749526] alg: No test for adler32 (adler32-zlib) [ 4162.849629] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 4163.201852] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 4165.009111] Key type lgssc registered [ 4166.340443] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4206.502979] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4320.841147] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4327.545649] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4351.849305] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4353.493189] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 4355.448154] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4357.204598] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 11:16:26 (1788275786) [ 4621.295401] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4631.637281] Lustre: Unmounted lustre-client [ 4766.624436] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4772.502470] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4797.187224] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4839.349591] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 11:24:27 (1788276267) [ 4930.038600] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4936.552291] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4960.739386] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4961.440333] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 4967.966391] Lustre: Unmounted lustre-client [ 5000.007411] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 11:27:08 (1788276428) [ 5092.054418] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5098.080702] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5122.261951] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5122.988344] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 5128.152960] Lustre: Unmounted lustre-client [ 5156.322509] Key type lgssc unregistered [ 5156.593420] LNet: 122664:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5156.601826] LNetError: 122664:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5156.619192] LNet: Removed LNI 192.168.203.54@tcp [ 5157.323926] Key type .llcrypt unregistered [ 5157.327730] Key type ._llcrypt unregistered [ 5173.297102] Key type ._llcrypt registered [ 5173.304179] Key type .llcrypt registered [ 5174.459196] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 11:30:03 (1788276603) [ 5176.516965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5176.540317] alg: No test for adler32 (adler32-zlib) [ 5177.540716] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 5177.785383] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 5179.455544] Key type lgssc registered [ 5180.626691] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5231.192748] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5239.783817] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5260.967398] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5263.632857] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 5277.055884] Lustre: Unmounted lustre-client [ 5303.861546] Key type lgssc unregistered [ 5304.050345] LNet: 126263:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5304.070516] LNetError: 126263:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5304.108326] LNet: Removed LNI 192.168.203.54@tcp [ 5304.779843] Key type .llcrypt unregistered [ 5304.784052] Key type ._llcrypt unregistered [ 5323.119414] Key type ._llcrypt registered [ 5323.123520] Key type .llcrypt registered [ 5324.871095] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 11:32:33 (1788276753) [ 5327.598658] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5327.627034] alg: No test for adler32 (adler32-zlib) [ 5328.770250] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 5329.089298] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 5330.800344] Key type lgssc registered [ 5331.970910] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5385.537303] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5391.402927] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5412.940443] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5415.645066] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 5433.159777] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9a66c309e800.ost_server_uuid 50 [ 5442.527157] Lustre: 127223:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788276856/real 1788276856] req@00000000a5845436 x1875144090851200/t0(0) o400->lustre-OST0000-osc-ffff9a66c309e800@192.168.203.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1788276872 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5442.573125] Lustre: lustre-OST0000-osc-ffff9a66c309e800: Connection to lustre-OST0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5444.239468] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5448.671178] Lustre: 127223:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788276861/real 1788276861] req@0000000029ef9347 x1875144090851712/t0(0) o400->lustre-OST0000-osc-ffff9a66c309e800@192.168.203.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1788276877 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5452.769238] Lustre: 127224:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788276866/real 1788276866] req@000000005faecb2f x1875144090852224/t0(0) o400->lustre-OST0000-osc-ffff9a66c309e800@192.168.203.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1788276882 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5456.836037] Lustre: Unmounted lustre-client [ 5478.421142] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 11:35:07 (1788276907) [ 5514.277261] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5519.943270] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5541.032068] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5563.674809] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5564.276969] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 5569.517643] Lustre: lustre-OST0000-osc-ffff9a66c68b4000: Connection to lustre-OST0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5600.060784] Lustre: Unmounted lustre-client [ 5647.597431] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 11:37:56 (1788277076) [ 5679.501642] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5684.913884] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5704.088519] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5725.833318] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5726.821837] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 5731.826065] Lustre: lustre-OST0000-osc-ffff9a66c44c8800: Connection to lustre-OST0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5749.111716] Lustre: Unmounted lustre-client [ 5797.631554] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 11:40:26 (1788277226) [ 5896.516775] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5902.992433] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5925.276406] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6002.003528] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6008.224947] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6030.884102] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6070.685342] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 6114.592720] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6116.461657] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6118.853906] Lustre: Unmounted lustre-client [ 6164.304437] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 11:46:32 (1788277592) [ 6199.747582] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6207.260428] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6233.274169] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6270.096973] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6300.255699] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 6342.808476] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6344.632933] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6356.990922] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6367.338807] Lustre: Unmounted lustre-client [ 6423.278828] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 11:50:51 (1788277851) [ 6464.199724] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6470.608269] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6494.012800] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6495.102734] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 6522.599628] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6525.023695] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6543.847180] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9a66c4725000.ost_server_uuid 50 [ 6545.587829] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9a66c4725000.ost_server_uuid in IDLE state after 0 sec [ 6549.473726] Lustre: Unmounted lustre-client [ 6549.741750] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6549.746053] Lustre: Skipped 3 previous similar messages [ 6549.870907] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 6551.326843] LustreError: 149763:0:(sec.c:403:import_sec_validate_get()) import 00000000296b3788 (NEW) with no sec [ 6615.683906] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 11:54:04 (1788278044) [ 6649.232597] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6655.225544] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6680.431415] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6701.933474] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6702.707431] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6702.947666] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 6714.589636] Lustre: Unmounted lustre-client [ 6714.594326] Lustre: Skipped 1 previous similar message [ 6749.774855] Key type lgssc unregistered [ 6750.146069] LNet: 153171:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6750.156125] LNetError: 153171:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6750.175092] LNet: Removed LNI 192.168.203.54@tcp [ 6751.319881] Key type .llcrypt unregistered [ 6751.322603] Key type ._llcrypt unregistered [ 6770.007408] Key type ._llcrypt registered [ 6770.014573] Key type .llcrypt registered [ 6771.332852] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 11:56:40 (1788278200) [ 6772.486422] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6772.635094] alg: No test for adler32 (adler32-zlib) [ 6773.706776] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 6774.111923] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 6775.855243] Key type lgssc registered [ 6777.086798] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6832.949462] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6839.326722] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6863.511374] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6886.806675] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6888.432471] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6889.593236] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 6896.662694] LustreError: lustre-MDT0001-mdc-ffff9a66d1309000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6896.684664] Lustre: lustre-MDT0001-mdc-ffff9a66d1309000: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 6903.164268] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6905.008901] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6927.879294] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6931.641641] Lustre: Unmounted lustre-client [ 6958.861593] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 11:59:47 (1788278387) [ 7054.140546] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7059.949446] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7086.184135] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7086.747689] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 7113.283107] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7115.126994] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7119.342922] Lustre: lustre-OST0001-osc-ffff9a66c4c5f000: Connection to lustre-OST0001 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7126.612736] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7136.944242] Lustre: Unmounted lustre-client [ 7169.426922] Key type lgssc unregistered [ 7169.669148] LNet: 162795:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7169.675974] LNetError: 162795:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7169.694752] LNet: Removed LNI 192.168.203.54@tcp [ 7170.347136] Key type .llcrypt unregistered [ 7170.350095] Key type ._llcrypt unregistered [ 7196.284775] Key type ._llcrypt registered [ 7196.286418] Key type .llcrypt registered [ 7197.511466] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 12:03:46 (1788278626) [ 7199.581153] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7199.622500] alg: No test for adler32 (adler32-zlib) [ 7200.692813] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 7200.951747] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 7202.599564] Key type lgssc registered [ 7203.844330] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7262.457182] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7268.027110] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7289.468778] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7291.192695] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 7338.855550] Lustre: Unmounted lustre-client [ 7384.995796] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7385.877223] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 7422.280474] Lustre: Unmounted lustre-client [ 7450.189488] Key type lgssc unregistered [ 7450.538110] LNet: 167243:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7450.555742] LNetError: 167243:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7450.587044] LNet: Removed LNI 192.168.203.54@tcp [ 7451.479774] Key type .llcrypt unregistered [ 7451.482188] Key type ._llcrypt unregistered [ 7467.764607] Key type ._llcrypt registered [ 7467.768600] Key type .llcrypt registered [ 7469.208291] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 12:08:17 (1788278897) [ 7472.726430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7472.775997] alg: No test for adler32 (adler32-zlib) [ 7474.005883] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 7474.327100] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 7476.039224] Key type lgssc registered [ 7477.367752] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7529.051364] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7535.220858] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7558.503343] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7560.496901] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 7586.323658] Lustre: Unmounted lustre-client [ 7612.920509] Key type lgssc unregistered [ 7613.131967] LNet: 171003:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7613.160187] LNetError: 171003:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7613.202644] LNet: Removed LNI 192.168.203.54@tcp [ 7613.825336] Key type .llcrypt unregistered [ 7613.829902] Key type ._llcrypt unregistered [ 7625.503645] Key type ._llcrypt registered [ 7625.505527] Key type .llcrypt registered [ 7626.215757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7626.228444] alg: No test for adler32 (adler32-zlib) [ 7627.297101] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 7627.614037] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 7629.295994] Key type lgssc registered [ 7630.445749] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7681.560921] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7687.270103] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7710.329709] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7712.131406] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 7722.463173] Lustre: Unmounted lustre-client [ 7751.574731] Key type lgssc unregistered [ 7751.878851] LNet: 174064:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7751.887710] LNetError: 174064:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7751.903130] LNet: Removed LNI 192.168.203.54@tcp [ 7752.623699] Key type .llcrypt unregistered [ 7752.627109] Key type ._llcrypt unregistered [ 7769.689215] Key type ._llcrypt registered [ 7769.693507] Key type .llcrypt registered [ 7771.159614] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 12:13:19 (1788279199) [ 7773.488602] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7773.655719] alg: No test for adler32 (adler32-zlib) [ 7774.700810] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 7774.961679] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 7776.623191] Key type lgssc registered [ 7777.911856] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7826.056496] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7831.871865] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7853.995687] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7855.829891] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 7880.838473] Lustre: Unmounted lustre-client [ 7917.712319] Key type lgssc unregistered [ 7917.972789] LNet: 177820:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7917.977930] LNetError: 177820:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7918.014522] LNet: Removed LNI 192.168.203.54@tcp [ 7918.706254] Key type .llcrypt unregistered [ 7918.710612] Key type ._llcrypt unregistered [ 7932.536496] Key type ._llcrypt registered [ 7932.538892] Key type .llcrypt registered [ 7933.005617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7933.017832] alg: No test for adler32 (adler32-zlib) [ 7934.032947] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 7934.223686] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 7935.887908] Key type lgssc registered [ 7937.049249] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7990.033347] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7995.424391] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8017.864500] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8020.572294] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 8028.989308] Lustre: Unmounted lustre-client [ 8058.611803] Key type lgssc unregistered [ 8059.111791] LNet: 180889:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8059.130742] LNetError: 180889:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8059.163475] LNet: Removed LNI 192.168.203.54@tcp [ 8060.308201] Key type .llcrypt unregistered [ 8060.311062] Key type ._llcrypt unregistered [ 8082.645993] Key type ._llcrypt registered [ 8082.658399] Key type .llcrypt registered [ 8084.367341] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 12:18:32 (1788279512) [ 8108.492469] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8108.525511] alg: No test for adler32 (adler32-zlib) [ 8109.705239] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 8110.211614] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 8111.887198] Key type lgssc registered [ 8113.733049] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8209.899850] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8216.416162] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8241.240616] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8285.453316] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 12:21:54 (1788279714) [ 8321.031369] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8326.908657] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8349.743792] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8352.608918] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 8358.221870] Lustre: Unmounted lustre-client [ 8394.614479] Key type lgssc unregistered [ 8394.954155] LNet: 187994:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8394.968432] LNetError: 187994:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8394.991465] LNet: Removed LNI 192.168.203.54@tcp [ 8395.670540] Key type .llcrypt unregistered [ 8395.672453] Key type ._llcrypt unregistered [ 8411.955436] Key type ._llcrypt registered [ 8411.961089] Key type .llcrypt registered [ 8413.392785] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8415.263986] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 12:24:03 (1788279843) [ 8434.218157] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8434.354609] alg: No test for adler32 (adler32-zlib) [ 8435.526919] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 8435.929143] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 8437.647138] Key type lgssc registered [ 8438.746642] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8527.824324] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8533.893931] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8554.717985] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8573.209279] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8575.800443] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 8594.349810] Lustre: Unmounted lustre-client [ 8721.033781] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8725.872080] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8748.000177] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8787.255175] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 12:30:16 (1788280216) [ 8788.651615] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8790.228492] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 12:30:19 (1788280219) [ 8883.818972] Key type lgssc unregistered [ 8884.042553] LNet: 199429:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8884.049656] LNetError: 199429:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8884.077804] LNet: Removed LNI 192.168.203.54@tcp [ 8884.713733] Key type .llcrypt unregistered [ 8884.720951] Key type ._llcrypt unregistered [ 8899.561805] Key type ._llcrypt registered [ 8899.564481] Key type .llcrypt registered [ 8900.791544] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 12:32:09 (1788280329) [ 8939.035835] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8939.045981] alg: No test for adler32 (adler32-zlib) [ 8940.120893] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 8940.406523] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 8942.112524] Key type lgssc registered [ 8943.370085] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8983.653666] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8988.527362] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9023.030665] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9051.419834] Key type lgssc unregistered [ 9051.608870] LNet: 203163:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9051.617570] LNetError: 203163:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9051.631833] LNet: Removed LNI 192.168.203.54@tcp [ 9052.035945] Key type .llcrypt unregistered [ 9052.037756] Key type ._llcrypt unregistered [ 9065.397440] Key type ._llcrypt registered [ 9065.406450] Key type .llcrypt registered [ 9066.636637] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 12:34:55 (1788280495) [ 9068.981338] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9069.054908] alg: No test for adler32 (adler32-zlib) [ 9070.104449] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 9070.323769] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 9072.007214] Key type lgssc registered [ 9072.893282] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9118.076306] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9123.605246] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9144.844908] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9147.561884] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 9163.237574] Lustre: lustre-MDT0001-mdc-ffff9a66d130f000: Connection to lustre-MDT0001 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9179.616097] Lustre: 204170:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788280593/real 1788280593] req@000000004968f244 x1875148013650048/t0(0) o400->MGC192.168.203.154@tcp@192.168.203.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1788280609 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9179.654922] LustreError: MGC192.168.203.154@tcp: Connection to MGS (at 192.168.203.154@tcp) was lost; in progress operations using this service will fail [ 9188.908124] Lustre: Evicted from MGS (at 192.168.203.154@tcp) after server handle changed from 0x946cbd302ea42309 to 0x946cbd302ea43df8 [ 9188.927423] Lustre: MGC192.168.203.154@tcp: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 9204.380286] LustreError: lustre-MDT0000-mdc-ffff9a66d130f000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9204.481816] Lustre: lustre-MDT0000-mdc-ffff9a66d130f000: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 9211.076566] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9212.747337] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9218.265775] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9219.885690] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9222.762676] Lustre: Unmounted lustre-client [ 9249.619650] Key type lgssc unregistered [ 9249.811288] LNet: 208128:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9249.819632] LNetError: 208128:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9249.840973] LNet: Removed LNI 192.168.203.54@tcp [ 9250.398556] Key type .llcrypt unregistered [ 9250.400884] Key type ._llcrypt unregistered [ 9266.359260] Key type ._llcrypt registered [ 9266.361302] Key type .llcrypt registered [ 9267.611907] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 12:38:16 (1788280696) [ 9269.946405] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9269.959555] alg: No test for adler32 (adler32-zlib) [ 9271.012662] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 9271.222482] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 9272.903291] Key type lgssc registered [ 9274.102185] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9320.867410] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9326.993857] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9379.039461] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9384.742964] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9420.091412] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9441.234508] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9490.177343] Key type lgssc unregistered [ 9490.496727] LNet: 213427:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9490.507669] LNetError: 213427:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9490.524996] LNet: Removed LNI 192.168.203.54@tcp [ 9491.241972] Key type .llcrypt unregistered [ 9491.248838] Key type ._llcrypt unregistered [ 9532.900445] Key type ._llcrypt registered [ 9532.902929] Key type .llcrypt registered [ 9534.360361] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 12:42:43 (1788280963) [ 9572.020207] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9572.035360] alg: No test for adler32 (adler32-zlib) [ 9573.135654] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [ 9573.401096] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [ 9575.113285] Key type lgssc registered [ 9576.482727] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9660.623107] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9666.117765] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9687.558880] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9725.531851] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 12:45:54 (1788281154) [ 9733.018364] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 12:46:01 (1788281161) [ 9766.803153] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9772.336585] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9793.709381] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9795.442577] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [ 9801.298532] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9803.179859] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9804.904664] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9806.890302] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9810.926510] Lustre: lustre-MDT0000-mdc-ffff9a66c87a6000: Connection to lustre-MDT0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9827.295336] Lustre: 215323:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788281241/real 1788281241] req@0000000002008d63 x1875148541011456/t0(0) o400->MGC192.168.203.154@tcp@192.168.203.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1788281257 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9827.348890] LustreError: MGC192.168.203.154@tcp: Connection to MGS (at 192.168.203.154@tcp) was lost; in progress operations using this service will fail [ 9837.576484] Lustre: Evicted from MGS (at 192.168.203.154@tcp) after server handle changed from 0xa34b220dcdc92e5b to 0xa34b220dcdc92fce [ 9837.601889] Lustre: MGC192.168.203.154@tcp: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 9845.197640] Lustre: lustre-MDT0000-mdc-ffff9a66c87a6000: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 9855.788376] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9857.414858] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9861.793468] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9868.268318] Lustre: lustre-MDT0000-mdc-ffff9a66c87a6000: Connection to lustre-MDT0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9884.642361] Lustre: 215322:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788281298/real 1788281298] req@00000000dc4f26d5 x1875148541021184/t0(0) o400->MGC192.168.203.154@tcp@192.168.203.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1788281314 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9884.680367] LustreError: MGC192.168.203.154@tcp: Connection to MGS (at 192.168.203.154@tcp) was lost; in progress operations using this service will fail [ 9884.705993] Lustre: Evicted from MGS (at 192.168.203.154@tcp) after server handle changed from 0xa34b220dcdc92fce to 0xa34b220dcdc932e5 [ 9884.717627] Lustre: MGC192.168.203.154@tcp: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 9890.806923] Lustre: 221316:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.203.154@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 9896.893595] Lustre: lustre-MDT0000-mdc-ffff9a66c87a6000: Connection restored to 192.168.203.154@tcp (at 192.168.203.154@tcp) [ 9907.322166] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9908.966260] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9912.970089] Lustre: Unmounted lustre-client [ 9986.156966] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9994.234838] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10022.938865] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10029.130137] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [10030.384044] Lustre: Unmounted lustre-client [10069.080914] Key type lgssc unregistered [10069.338226] LNet: 225916:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10069.342827] LNetError: 225916:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10069.357303] LNet: Removed LNI 192.168.203.54@tcp [10069.975996] Key type .llcrypt unregistered [10069.978098] Key type ._llcrypt unregistered [10085.482639] Key type ._llcrypt registered [10085.485900] Key type .llcrypt registered [10086.771878] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 12:51:55 (1788281515) [10091.615268] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10091.690988] alg: No test for adler32 (adler32-zlib) [10092.877628] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [10093.207896] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [10094.943303] Key type lgssc registered [10096.216976] Lustre: Echo OBD driver; http://www.lustre.org/ [10146.438540] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10153.279529] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10174.647292] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10177.569661] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [10188.971730] Lustre: Unmounted lustre-client [10265.278807] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10271.543830] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10295.952475] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10307.146218] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [10317.474300] Lustre: Unmounted lustre-client [10390.411621] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10396.868607] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10423.355773] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10423.980655] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [10426.549461] Lustre: Unmounted lustre-client [10455.487785] Key type lgssc unregistered [10455.850663] LNet: 232977:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10455.857297] LNetError: 232977:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10455.871573] LNet: Removed LNI 192.168.203.54@tcp [10456.626264] Key type .llcrypt unregistered [10456.630958] Key type ._llcrypt unregistered [10473.492899] Key type ._llcrypt registered [10473.494508] Key type .llcrypt registered [10474.759726] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 12:58:23 (1788281903) [10477.783609] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10477.823960] alg: No test for adler32 (adler32-zlib) [10478.922408] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [10479.147359] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [10480.855178] Key type lgssc registered [10482.104768] Lustre: Echo OBD driver; http://www.lustre.org/ [10530.018226] Key type lgssc unregistered [10530.249485] LNet: 235303:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10530.259434] LNetError: 235303:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10530.297270] LNet: Removed LNI 192.168.203.54@tcp [10531.028237] Key type .llcrypt unregistered [10531.043047] Key type ._llcrypt unregistered [10563.812286] Key type ._llcrypt registered [10563.815281] Key type .llcrypt registered [10564.541780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10564.578734] alg: No test for adler32 (adler32-zlib) [10565.758451] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [10566.018851] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [10567.639145] Key type lgssc registered [10569.350649] Lustre: Echo OBD driver; http://www.lustre.org/ [10676.853271] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10683.735978] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10713.719590] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10759.932383] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10761.824723] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 13:03:10 (1788282190) [10796.165400] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10802.473769] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10823.612635] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10852.595497] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10855.618675] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [10861.039076] Lustre: lustre-OST0001-osc-ffff9a66c8dcb800: Connection to lustre-OST0001 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [10868.963512] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10879.361163] Lustre: Unmounted lustre-client [10914.611412] Key type lgssc unregistered [10914.975119] LNet: 242443:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10914.985836] LNetError: 242443:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10915.010597] LNet: Removed LNI 192.168.203.54@tcp [10915.949929] Key type .llcrypt unregistered [10915.956407] Key type ._llcrypt unregistered [10952.427874] Key type ._llcrypt registered [10952.433803] Key type .llcrypt registered [10954.142794] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 13:06:22 (1788282382) [10970.139684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10970.156591] alg: No test for adler32 (adler32-zlib) [10971.176436] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [10971.479923] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [10973.159469] Key type lgssc registered [10974.707669] Lustre: Echo OBD driver; http://www.lustre.org/ [11026.912567] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11033.794515] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11060.892829] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 13:08:09 (1788282489) [11094.448327] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11100.110329] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11121.643846] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11124.493427] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [11131.195429] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11146.235941] Lustre: Unmounted lustre-client [11268.410419] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11277.298744] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11328.146117] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11328.909986] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [11332.187034] Lustre: Unmounted lustre-client [11362.516390] Key type lgssc unregistered [11362.795143] LNet: 250142:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11362.801435] LNetError: 250142:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11362.816591] LNet: Removed LNI 192.168.203.54@tcp [11363.600883] Key type .llcrypt unregistered [11363.603280] Key type ._llcrypt unregistered [11399.328410] Key type ._llcrypt registered [11399.342499] Key type .llcrypt registered [11400.077863] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11400.145274] alg: No test for adler32 (adler32-zlib) [11401.560228] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [11402.004991] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [11403.711278] Key type lgssc registered [11404.945760] Lustre: Echo OBD driver; http://www.lustre.org/ [11506.002372] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11512.246582] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11540.730977] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11587.318402] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11589.855265] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 13:16:58 (1788283018) [11624.633977] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11630.358505] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11652.765329] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11657.595607] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [11662.037029] Lustre: Unmounted lustre-client [11702.460530] Key type lgssc unregistered [11702.721159] LNet: 256792:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11702.730127] LNetError: 256792:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11702.748652] LNet: Removed LNI 192.168.203.54@tcp [11703.735415] Key type .llcrypt unregistered [11703.737340] Key type ._llcrypt unregistered [11723.933202] Key type ._llcrypt registered [11723.936444] Key type .llcrypt registered [11726.058726] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11728.261810] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 13:19:16 (1788283156) [11738.864756] Key type .llcrypt unregistered [11738.869847] Key type ._llcrypt unregistered [11751.041582] Key type ._llcrypt registered [11751.045068] Key type .llcrypt registered [11751.466328] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11751.486934] alg: No test for adler32 (adler32-zlib) [11752.636696] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [11752.981253] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [11754.792361] Key type lgssc registered [11756.370032] Lustre: Echo OBD driver; http://www.lustre.org/ [11809.844408] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11824.454358] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [11827.461477] Lustre: Unmounted lustre-client [11854.726924] Key type lgssc unregistered [11855.003709] LNet: 260252:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11855.015230] LNetError: 260252:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11855.041183] LNet: Removed LNI 192.168.203.54@tcp [11855.731303] Key type .llcrypt unregistered [11855.736315] Key type ._llcrypt unregistered [11869.984118] Key type ._llcrypt registered [11869.990130] Key type .llcrypt registered [11871.248525] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 13:21:40 (1788283300) [11873.371524] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11873.391900] alg: No test for adler32 (adler32-zlib) [11874.569584] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [11874.878790] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [11876.608091] Key type lgssc registered [11877.769670] Lustre: Echo OBD driver; http://www.lustre.org/ [11963.939614] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11997.830964] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12004.906731] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12007.202426] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [12012.327939] Lustre: Unmounted lustre-client [12043.161431] Key type lgssc unregistered [12043.412616] LNet: 263796:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12043.417916] LNetError: 263796:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12043.438166] LNet: Removed LNI 192.168.203.54@tcp [12044.036108] Key type .llcrypt unregistered [12044.039495] Key type ._llcrypt unregistered [12063.232497] Key type ._llcrypt registered [12063.235104] Key type .llcrypt registered [12064.826264] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 13:24:53 (1788283493) [12067.792720] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12067.806417] alg: No test for adler32 (adler32-zlib) [12069.034430] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [12069.321930] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [12071.015412] Key type lgssc registered [12072.253465] Lustre: Echo OBD driver; http://www.lustre.org/ [12129.330926] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12136.191728] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12158.778219] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12161.601704] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [12166.663848] Lustre: lustre-MDT0000-mdc-ffff9a66c96af800: Connection to lustre-MDT0000 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [12173.548629] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12173.594903] LustreError: 266953:0:(file.c:6166:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12174.067430] LustreError: MGC192.168.203.154@tcp: Connection to MGS (at 192.168.203.154@tcp) was lost; in progress operations using this service will fail [12174.112130] Lustre: Unmounted lustre-client [12196.231560] Key type lgssc unregistered [12196.471712] LNet: 267368:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12196.489918] LNetError: 267368:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12196.528760] LNet: Removed LNI 192.168.203.54@tcp [12197.321317] Key type .llcrypt unregistered [12197.328267] Key type ._llcrypt unregistered [12216.151811] Key type ._llcrypt registered [12216.155396] Key type .llcrypt registered [12217.752406] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 13:27:26 (1788283646) [12220.328810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12220.511570] alg: No test for adler32 (adler32-zlib) [12221.790618] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [12222.087433] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [12223.863260] Key type lgssc registered [12225.077603] Lustre: Echo OBD driver; http://www.lustre.org/ [12288.225965] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12294.056211] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12318.157423] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12320.803358] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [12325.892739] Lustre: lustre-MDT0001-mdc-ffff9a66c309c000: Connection to lustre-MDT0001 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [12328.831833] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12331.322556] Lustre: Unmounted lustre-client [12352.739433] Key type lgssc unregistered [12353.069796] LNet: 270953:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12353.089093] LNetError: 270953:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12353.107573] LNet: Removed LNI 192.168.203.54@tcp [12353.915216] Key type .llcrypt unregistered [12353.917762] Key type ._llcrypt unregistered [12371.649616] Key type ._llcrypt registered [12371.652713] Key type .llcrypt registered [12373.079112] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 13:30:01 (1788283801) [12393.780505] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12393.805932] alg: No test for adler32 (adler32-zlib) [12394.911562] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [12395.123460] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [12396.812720] Key type lgssc registered [12398.741391] Lustre: Echo OBD driver; http://www.lustre.org/ [12524.430336] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12527.194977] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [12567.532798] Lustre: lustre-MDT0001-mdc-ffff9a66d8430000: Connection to lustre-MDT0001 (at 192.168.203.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [12578.869289] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12589.210701] Lustre: Unmounted lustre-client [12618.568664] Key type lgssc unregistered [12618.779599] LNet: 276751:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12618.788906] LNetError: 276751:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12618.806443] LNet: Removed LNI 192.168.203.54@tcp [12619.603396] Key type .llcrypt unregistered [12619.608109] Key type ._llcrypt unregistered [12638.048916] Key type ._llcrypt registered [12638.051394] Key type .llcrypt registered [12639.346464] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 13:34:28 (1788284068) [12641.396811] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12643.664470] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 13:34:31 (1788284071) [12645.532101] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12647.967470] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 13:34:36 (1788284076) [12650.412832] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12652.285057] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 13:34:41 (1788284081) [12654.499541] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12656.561660] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 13:34:45 (1788284085) [12658.497807] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12661.104946] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 13:34:49 (1788284089) [12670.916280] Key type .llcrypt unregistered [12670.917962] Key type ._llcrypt unregistered [12678.993374] Key type ._llcrypt registered [12678.999225] Key type .llcrypt registered [12679.774672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12679.821572] alg: No test for adler32 (adler32-zlib) [12680.996781] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [12681.281113] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [12683.026506] Key type lgssc registered [12684.666495] Lustre: Echo OBD driver; http://www.lustre.org/ [12769.480344] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12777.153194] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12804.032956] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12805.682281] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [12807.975063] Lustre: Unmounted lustre-client [12847.762175] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 13:37:56 (1788284276) [12884.275891] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12890.287551] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12925.350332] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12931.667663] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [12933.495419] Lustre: Unmounted lustre-client [12982.495226] LNet: 1 local NIs in recovery (showing 1): 192.168.203.54@tcp [12988.525294] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 13:40:17 (1788284417) [13647.684323] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13654.283279] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13694.827502] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13819.290847] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 13:54:07 (1788285247) [13832.314788] Key type lgssc unregistered [13832.720133] LNet: 290311:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13832.733114] LNetError: 290311:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13832.752423] LNet: Removed LNI 192.168.203.54@tcp [13833.642779] Key type .llcrypt unregistered [13833.648744] Key type ._llcrypt unregistered [13939.783294] Key type ._llcrypt registered [13939.796145] Key type .llcrypt registered [13940.101462] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13940.123615] alg: No test for adler32 (adler32-zlib) [13941.257197] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [13941.588379] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [13943.447280] Key type lgssc registered [13945.174430] Lustre: Echo OBD driver; http://www.lustre.org/ [14044.179469] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14050.396524] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14075.942551] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14112.173810] Key type lgssc unregistered [14112.525921] LNet: 294015:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14112.534958] LNetError: 294015:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14112.561456] LNet: Removed LNI 192.168.203.54@tcp [14113.476113] Key type .llcrypt unregistered [14113.482391] Key type ._llcrypt unregistered [14150.436263] Key type ._llcrypt registered [14150.444685] Key type .llcrypt registered [14152.062867] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 13:59:40 (1788285580) [14178.471910] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14178.729930] alg: No test for adler32 (adler32-zlib) [14179.933102] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [14180.291503] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [14182.040979] Key type lgssc registered [14183.230942] Lustre: Echo OBD driver; http://www.lustre.org/ [14242.416045] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 14:01:10 (1788285670) [14255.161987] Key type lgssc unregistered [14255.460735] LNet: 297588:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14255.473344] LNetError: 297588:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14255.495528] LNet: Removed LNI 192.168.203.54@tcp [14256.235927] Key type .llcrypt unregistered [14256.239196] Key type ._llcrypt unregistered [14268.578558] Key type ._llcrypt registered [14268.581834] Key type .llcrypt registered [14269.219626] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14269.245027] alg: No test for adler32 (adler32-zlib) [14270.378469] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [14270.787700] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [14272.503271] Key type lgssc registered [14274.554113] Lustre: Echo OBD driver; http://www.lustre.org/ [14338.154340] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14344.254599] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14373.048091] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14694.266378] LNet: Added LNI 192.168.203.54@tcp50 [8/256/0/180] [14696.658969] LNet: Removed LNI 192.168.203.54@tcp [14698.960082] Lustre: 302011:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9a66c309c000: config has no valid NIDs, force dynamic NIDs [14700.202523] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [14700.426273] Lustre: Unmounted lustre-client [14703.047982] Key type lgssc unregistered [14703.582505] LNet: 302274:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14703.591686] LNetError: 302274:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14703.619536] LNet: Removed LNI 192.168.203.54@tcp50 [14704.389809] Key type .llcrypt unregistered [14704.395993] Key type ._llcrypt unregistered [14723.493737] Key type ._llcrypt registered [14723.503526] Key type .llcrypt registered [14723.928538] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14723.943972] alg: No test for adler32 (adler32-zlib) [14725.176605] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [14725.442119] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [14727.107517] Key type lgssc registered [14728.471962] Lustre: Echo OBD driver; http://www.lustre.org/ [14792.337313] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14798.268933] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14805.800508] LNet: Added LNI 192.168.203.54@tcp50 [8/256/0/180] [14806.728194] LNet: Removed LNI 192.168.203.54@tcp [14807.937177] Lustre: 304969:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9a66c5e88800: config has no valid NIDs, force dynamic NIDs [14809.169953] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [14809.949629] Lustre: Unmounted lustre-client [14844.476836] Key type lgssc unregistered [14844.824912] LNet: 305840:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14844.836980] LNetError: 305840:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14844.856368] LNet: Removed LNI 192.168.203.54@tcp50 [14845.520276] Key type .llcrypt unregistered [14845.522940] Key type ._llcrypt unregistered [14857.657747] Key type ._llcrypt registered [14857.668530] Key type .llcrypt registered [14859.097288] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 14:11:27 (1788286287) [14879.763768] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14879.807628] alg: No test for adler32 (adler32-zlib) [14881.073847] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [14881.443205] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [14883.199236] Key type lgssc registered [14884.635351] Lustre: Echo OBD driver; http://www.lustre.org/ [14971.788930] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14978.802264] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15006.726786] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15052.124363] Key type lgssc unregistered [15052.407832] LNet: 310595:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15052.435157] LNetError: 310595:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15052.454540] LNet: Removed LNI 192.168.203.54@tcp [15053.187828] Key type .llcrypt unregistered [15053.189683] Key type ._llcrypt unregistered [15065.183816] Key type ._llcrypt registered [15065.185476] Key type .llcrypt registered [15066.677448] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 14:14:55 (1788286495) [15068.989175] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15069.007810] alg: No test for adler32 (adler32-zlib) [15070.159413] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [15070.505456] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [15072.255187] Key type lgssc registered [15073.867447] Lustre: Echo OBD driver; http://www.lustre.org/ [15129.544607] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15136.559788] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15159.104260] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15161.888147] Lustre: Mounted lustre-client - version 2.17.57_64_g65721f0 [15227.021056] Lustre: Mounted fs15246-client - version 2.17.57_64_g65721f0 [15243.011407] Lustre: Unmounted fs15246-client [15272.471775] Lustre: Unmounted lustre-client [15309.413602] Key type lgssc unregistered [15309.694447] LNet: 315464:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15309.713187] LNetError: 315464:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15310.766721] LNet: Removed LNI 192.168.203.54@tcp [15311.612920] Key type .llcrypt unregistered [15311.617827] Key type ._llcrypt unregistered [15323.721605] Key type ._llcrypt registered [15323.725656] Key type .llcrypt registered [15325.278324] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 14:19:13 (1788286753) [15326.969094] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15327.003386] alg: No test for adler32 (adler32-zlib) [15328.204812] Lustre: Lustre: Build Version: 2.17.57_64_g65721f0 [15328.565639] LNet: Added LNI 192.168.203.54@tcp [8/256/0/180] [15330.273124] Key type lgssc registered [15331.425550] Lustre: Echo OBD driver; http://www.lustre.org/ [15470.896486] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15477.007835] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15500.890445] Lustre: DEBUG MARKER: oleg354-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15615.171536] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15377 sec ========= 14:24:03 (1788287043) [15617.050344] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 14:24:05 (1788287045) === [15620.752438] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 14:24:09 (1788287049) === [15656.685680] Key type lgssc unregistered [15657.116990] LNet: 324737:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15657.130689] LNetError: 324737:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15657.150372] LNet: Removed LNI 192.168.203.54@tcp [15658.136478] Key type .llcrypt unregistered [15658.146439] Key type ._llcrypt unregistered