[ 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 425523868 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.003323] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.007288] ..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.008018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009010] pid_max: default: 32768 minimum: 301 [ 0.010097] LSM: Security Framework initializing [ 0.011047] Yama: becoming mindful. [ 0.012040] SELinux: Initializing. [ 0.013050] *** VALIDATE selinux *** [ 0.021366] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026066] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027143] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028106] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029102] *** VALIDATE tmpfs *** [ 0.031114] *** VALIDATE proc *** [ 0.032221] *** VALIDATE cgroup *** [ 0.033011] *** VALIDATE cgroup2 *** [ 0.034255] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035144] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036007] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037025] Spectre V2 : User space: Vulnerable [ 0.038008] Speculative Store Bypass: Vulnerable [ 0.041372] debug: unmapping init [mem 0xffffffff9d259000-0xffffffff9d260fff] [ 0.042177] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043691] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044018] ... version: 2 [ 0.045008] ... bit width: 48 [ 0.046010] ... generic registers: 4 [ 0.047010] ... value mask: 0000ffffffffffff [ 0.048016] ... max period: 00007fffffffffff [ 0.049012] ... fixed-purpose events: 3 [ 0.050012] ... event mask: 000000070000000f [ 0.051265] rcu: Hierarchical SRCU implementation. [ 0.053285] smp: Bringing up secondary CPUs ... [ 0.054576] x86: Booting SMP configuration: [ 0.055025] .... node #0, CPUs: #1 #2 #3 [ 0.058396] smp: Brought up 1 node, 4 CPUs [ 0.059989] smpboot: Max logical packages: 1 [ 0.060011] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.132000] node 0 deferred pages initialised in 68ms [ 0.134107] devtmpfs: initialized [ 0.135200] x86/mm: Memory block size: 128MB [ 0.137782] gcov: version magic: 0x41383552 [ 0.138562] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.142081] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.144253] pinctrl core: initialized pinctrl subsystem [ 0.146121] [ 0.146625] ************************************************************* [ 0.148008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.150011] ** ** [ 0.152010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.155012] ** ** [ 0.157009] ** This means that this kernel is built to expose internal ** [ 0.159011] ** IOMMU data structures, which may compromise security on ** [ 0.161010] ** your system. ** [ 0.164012] ** ** [ 0.166008] ** If you see this message and you are not debugging the ** [ 0.167007] ** kernel, report this immediately to your vendor! ** [ 0.169010] ** ** [ 0.170007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.172009] ************************************************************* [ 0.174627] NET: Registered protocol family 16 [ 0.175380] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.177047] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.180058] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.184068] cpuidle: using governor menu [ 0.185854] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.188281] PCI: Using configuration type 1 for base access [ 0.191250] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.200128] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.201037] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.203052] cryptd: max_cpu_qlen set to 1000 [ 0.207396] ACPI: Added _OSI(Module Device) [ 0.209015] ACPI: Added _OSI(Processor Device) [ 0.211013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.212013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.217066] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.223267] ACPI: Interpreter enabled [ 0.224044] ACPI: PM: (supports S0 S3 S4 S5) [ 0.225014] ACPI: Using IOAPIC for interrupt routing [ 0.227096] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.229301] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.238343] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.240038] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.243017] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.246063] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.251333] acpiphp: Slot [2] registered [ 0.253105] acpiphp: Slot [5] registered [ 0.254160] acpiphp: Slot [6] registered [ 0.256098] acpiphp: Slot [3] registered [ 0.257095] acpiphp: Slot [4] registered [ 0.259121] acpiphp: Slot [7] registered [ 0.260068] acpiphp: Slot [8] registered [ 0.262090] acpiphp: Slot [9] registered [ 0.263065] acpiphp: Slot [10] registered [ 0.264095] acpiphp: Slot [11] registered [ 0.265084] acpiphp: Slot [12] registered [ 0.267070] acpiphp: Slot [13] registered [ 0.268070] acpiphp: Slot [14] registered [ 0.269066] acpiphp: Slot [15] registered [ 0.271101] acpiphp: Slot [16] registered [ 0.273112] acpiphp: Slot [17] registered [ 0.274132] acpiphp: Slot [18] registered [ 0.275082] acpiphp: Slot [19] registered [ 0.277091] acpiphp: Slot [20] registered [ 0.278116] acpiphp: Slot [21] registered [ 0.280085] acpiphp: Slot [22] registered [ 0.281084] acpiphp: Slot [23] registered [ 0.283117] acpiphp: Slot [24] registered [ 0.284091] acpiphp: Slot [25] registered [ 0.287116] acpiphp: Slot [26] registered [ 0.288104] acpiphp: Slot [27] registered [ 0.290103] acpiphp: Slot [28] registered [ 0.291118] acpiphp: Slot [29] registered [ 0.293082] acpiphp: Slot [30] registered [ 0.294070] acpiphp: Slot [31] registered [ 0.295051] PCI host bridge to bus 0000:00 [ 0.296014] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.299022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.301020] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.303023] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.306023] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.311025] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.312183] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.315987] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.319386] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.324481] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.329039] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.332018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.334016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.336017] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.338590] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.341831] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.345093] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.347789] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.353021] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.364021] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.369019] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.374962] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.382017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.388013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.401995] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.407000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.414014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.420012] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.437021] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.449630] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.451345] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.453360] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.456357] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.459287] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.463136] iommu: Default domain type: Passthrough [ 0.464000] SCSI subsystem initialized [ 0.464050] ACPI: bus type USB registered [ 0.465000] usbcore: registered new interface driver usbfs [ 0.468081] usbcore: registered new interface driver hub [ 0.470096] usbcore: registered new device driver usb [ 0.471189] pps_core: LinuxPPS API ver. 1 registered [ 0.474012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.477055] PTP clock support registered [ 0.479070] EDAC MC: Ver: 3.0.0 [ 0.481186] PCI: Using ACPI for IRQ routing [ 0.482706] NetLabel: Initializing [ 0.483010] NetLabel: domain hash size = 128 [ 0.485014] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.487068] NetLabel: unlabeled traffic allowed by default [ 0.489078] vgaarb: loaded [ 0.490273] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.491000] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.495428] clocksource: Switched to clocksource kvm-clock [ 0.596667] VFS: Disk quotas dquot_6.6.0 [ 0.597967] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.600445] *** VALIDATE ramfs *** [ 0.601561] *** VALIDATE hugetlbfs *** [ 0.603034] pnp: PnP ACPI init [ 0.605395] pnp: PnP ACPI: found 6 devices [ 0.622417] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.625790] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.627681] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.629474] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.631623] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.633868] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.636052] NET: Registered protocol family 2 [ 0.638568] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.643614] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.647455] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.652202] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.654763] TCP: Hash tables configured (established 65536 bind 65536) [ 0.657037] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.660220] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.662648] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.667112] NET: Registered protocol family 1 [ 0.669671] RPC: Registered named UNIX socket transport module. [ 0.672191] RPC: Registered udp transport module. [ 0.674558] RPC: Registered tcp transport module. [ 0.676715] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.679152] NET: Registered protocol family 44 [ 0.680827] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.682969] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.685110] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.687452] PCI: CLS 0 bytes, default 64 [ 0.689177] Unpacking initramfs... [ 2.083649] debug: unmapping init [mem 0xffff8eb5fcc64000-0xffff8eb5fffcffff] [ 2.087244] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.089601] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.092111] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.585679] Initialise system trusted keyrings [ 2.587277] Key type blacklist registered [ 2.589430] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.599897] zbud: loaded [ 2.603394] *** VALIDATE nfs *** [ 2.604843] *** VALIDATE nfs4 *** [ 2.606542] pstore: using deflate compression [ 2.609584] Platform Keyring initialized [ 2.713597] NET: Registered protocol family 38 [ 2.715552] Key type asymmetric registered [ 2.717136] Asymmetric key parser 'x509' registered [ 2.719131] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.724042] io scheduler mq-deadline registered [ 2.725396] io scheduler kyber registered [ 2.727114] io scheduler bfq registered [ 2.729464] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.732749] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.736502] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.739534] ACPI: Power Button [PWRF] [ 2.745140] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.751734] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.761708] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.789593] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.819337] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.824509] Non-volatile memory driver v1.3 [ 2.826309] Linux agpgart interface v0.103 [ 2.861680] virtio_blk virtio1: [vda] 145896 512-byte logical blocks (74.7 MB/71.2 MiB) [ 2.865257] vda: detected capacity change from 0 to 74698752 [ 2.881657] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.884874] vdb: detected capacity change from 0 to 1073741824 [ 2.898048] libphy: Fixed MDIO Bus: probed [ 2.904306] usbcore: registered new interface driver usbserial_generic [ 2.907182] usbserial: USB Serial support registered for generic [ 2.910161] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.914627] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.916820] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.919600] mousedev: PS/2 mouse device common for all mice [ 2.923315] rtc_cmos 00:05: RTC can wake from S4 [ 2.924927] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.927959] rtc_cmos 00:05: registered as rtc0 [ 2.932575] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.935923] intel_pstate: CPU model not supported [ 2.938777] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.942533] hid: raw HID events driver (C) Jiri Kosina [ 2.945353] usbcore: registered new interface driver usbhid [ 2.945453] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.947949] usbhid: USB HID core driver [ 2.948184] drop_monitor: Initializing network drop monitor service [ 2.956539] Initializing XFRM netlink socket [ 2.958694] NET: Registered protocol family 10 [ 2.961788] Segment Routing with IPv6 [ 2.963716] NET: Registered protocol family 17 [ 2.966197] mpls_gso: MPLS GSO support [ 2.971921] RAS: Correctable Errors collector initialized. [ 2.974207] AVX version of gcm_enc/dec engaged. [ 2.975883] AES CTR mode by8 optimization enabled [ 3.054738] sched_clock: Marking stable (3054708774, 0)->(3953493826, -898785052) [ 3.058081] registered taskstats version 1 [ 3.060157] Loading compiled-in X.509 certificates [ 3.062062] zswap: loaded using pool lzo/zbud [ 3.086711] Key type big_key registered [ 3.101409] Key type encrypted registered [ 3.104407] ima: No TPM chip found, activating TPM-bypass! [ 3.106719] ima: Allocated hash algorithm: sha1 [ 3.108543] ima: No architecture policies found [ 3.110354] evm: Initialising EVM extended attributes: [ 3.112492] evm: security.selinux [ 3.113826] evm: security.ima [ 3.115045] evm: security.capability [ 3.116472] evm: HMAC attrs: 0x1 [ 3.118845] rtc_cmos 00:05: setting system clock to 2026-08-15 16:53:52 UTC (1786812832) [ 3.124952] debug: unmapping init [mem 0xffffffff9e203000-0xffffffff9e3fffff] [ 3.129862] debug: unmapping init [mem 0xffffffff9cf82000-0xffffffff9d258fff] [ 3.139266] Write protecting the kernel read-only data: 28672k [ 3.142714] debug: unmapping init [mem 0xffffffff9b603000-0xffffffff9b7fffff] [ 3.145148] debug: unmapping init [mem 0xffffffff9bf14000-0xffffffff9bffffff] [ 3.180180] 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.190370] systemd[1]: Detected virtualization kvm. [ 3.192396] systemd[1]: Detected architecture x86-64. [ 3.194359] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.221177] systemd[1]: No hostname configured. [ 3.222695] systemd[1]: Set hostname to . [ 3.225028] random: systemd: uninitialized urandom read (16 bytes read) [ 3.227326] systemd[1]: Initializing machine ID from random generator. [ 3.368263] random: systemd: uninitialized urandom read (16 bytes read) [ 3.371355] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.376457] random: systemd: uninitialized urandom read (16 bytes read) [ 3.379198] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.383386] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Slices. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local File Systems. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. Starting Journal Service... Starting Apply Kernel Variables... Starting Setup Virtual Console... Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.054780] device-mapper: uevent: version 1.0.3 [ 4.057359] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.828097] virtio_net virtio0 ens2: renamed from eth0 [ 4.836618] scsi host0: ata_piix [ 4.838811] scsi host1: ata_piix [ 4.855137] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.858033] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.630055] dracut-initqueue[578]: RTNETLINK answers: File exists [ 9.621619] random: crng init done [ 9.623513] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.122179] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ 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 Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.327946] printk: systemd: 24 output lines suppressed due to ratelimiting [ 11.604299] SELinux: Disabled at runtime. [ 11.665396] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.675497] systemd[1]: Detected virtualization kvm. [ 11.677220] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.209289] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.213320] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.219788] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.223646] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.227276] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.235790] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.239942] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... Mounting Huge Pages File System... Starting Remount Root and Kernel File Systems... [ 12.292989] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting POSIX Message Queue File System... [ OK ] Stopped target Switch Root. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on RPCbind Server Activation Socket. Starting Apply Kernel Variables... [ OK ] Reached target Slices. [ OK ] Stopped target Initrd Root File System. [ OK ] Reached target rpc_pipefs.target. Mounting Kernel Debug File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.738555] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.023470] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.160917] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.240938] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.264820] EDAC sbridge: Ver: 1.1.2 [ 14.401162] Key type dns_resolver registered [ 14.741958] NFS: Registering the id_resolver key type [ 14.745614] Key type id_resolver registered [ 14.747159] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ 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 System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg627-client login: [ 53.772647] libcfs: loading out-of-tree module taints kernel. [ 53.891734] Key type ._llcrypt registered [ 53.898871] Key type .llcrypt registered [ 54.755650] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 54.773556] alg: No test for adler32 (adler32-zlib) [ 56.278873] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 57.275705] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 59.083681] Key type lgssc registered [ 60.491601] Lustre: Echo OBD driver; http://www.lustre.org/ [ 109.311411] hrtimer: interrupt took 4852982 ns [ 234.935318] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 239.569708] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 250.964431] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing check_logdir /tmp/testlogs/ [ 257.348713] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing yml_node [ 260.576323] Lustre: lustre-OST0000-osc-ffff8eb6523d7800: disconnect after 23s idle [ 262.151679] Lustre: DEBUG MARKER: Client: 2.17.57.1 [ 265.869726] Lustre: DEBUG MARKER: MDS: 2.17.57.1 [ 269.941883] Lustre: DEBUG MARKER: OSS: 2.17.57.1 [ 271.958952] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sat Aug 15 12:58:19 EDT 2026 [ 288.023480] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 289.336899] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 291.707034] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 302.069043] Lustre: Unmounted lustre-client [ 407.406971] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 414.160384] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 445.079760] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 480.037712] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 13:01:48 (1786813308) [ 482.673864] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 484.782024] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 13:01:52 (1786813312) [ 526.702138] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 532.240158] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 556.116247] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 556.844745] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 565.016834] Lustre: Unmounted lustre-client [ 601.454725] Key type lgssc unregistered [ 601.661332] LNet: 15072:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 601.668424] LNetError: 15072:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 601.685316] LNet: Removed LNI 192.168.206.27@tcp [ 602.245187] Key type .llcrypt unregistered [ 602.250109] Key type ._llcrypt unregistered [ 616.441929] Key type ._llcrypt registered [ 616.445213] Key type .llcrypt registered [ 617.740508] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 13:04:05 (1786813445) [ 619.261915] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 619.294652] alg: No test for adler32 (adler32-zlib) [ 620.415857] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 620.751154] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 622.511487] Key type lgssc registered [ 623.785573] Lustre: Echo OBD driver; http://www.lustre.org/ [ 712.279362] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 802.746577] Lustre: Unmounted lustre-client [ 833.512950] Key type lgssc unregistered [ 833.724900] LNet: 18888:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 833.730579] LNetError: 18888:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 833.753184] LNet: Removed LNI 192.168.206.27@tcp [ 834.392627] Key type .llcrypt unregistered [ 834.399928] Key type ._llcrypt unregistered [ 843.792851] Key type ._llcrypt registered [ 843.795835] Key type .llcrypt registered [ 845.070716] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 13:07:52 (1786813672) [ 847.830767] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 847.882456] alg: No test for adler32 (adler32-zlib) [ 848.890325] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 849.123486] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 850.784369] Key type lgssc registered [ 851.726120] Lustre: Echo OBD driver; http://www.lustre.org/ [ 898.613639] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 906.374685] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 932.273183] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 935.116925] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 947.409664] Lustre: Unmounted lustre-client [ 975.698991] Key type lgssc unregistered [ 975.957791] LNet: 22496:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 975.966545] LNetError: 22496:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 975.978655] LNet: Removed LNI 192.168.206.27@tcp [ 976.759888] Key type .llcrypt unregistered [ 976.761945] Key type ._llcrypt unregistered [ 987.079662] Key type ._llcrypt registered [ 987.082349] Key type .llcrypt registered [ 988.197324] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 13:10:16 (1786813816) [ 990.444578] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 990.468681] alg: No test for adler32 (adler32-zlib) [ 991.553080] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 991.781415] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 993.463205] Key type lgssc registered [ 994.640947] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1046.111142] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1051.243850] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1070.592523] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1073.218679] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 1078.174478] Lustre: Unmounted lustre-client [ 1112.225490] Key type lgssc unregistered [ 1112.469329] LNet: 25688:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1112.475723] LNetError: 25688:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1112.494511] LNet: Removed LNI 192.168.206.27@tcp [ 1113.171849] Key type .llcrypt unregistered [ 1113.183268] Key type ._llcrypt unregistered [ 1125.661486] Key type ._llcrypt registered [ 1125.663193] Key type .llcrypt registered [ 1126.759459] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 13:12:34 (1786813954) [ 1128.561376] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1128.667930] alg: No test for adler32 (adler32-zlib) [ 1129.661321] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 1129.909768] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 1131.559347] Key type lgssc registered [ 1132.591316] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1171.270287] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1176.465732] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1193.323391] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1200.170415] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 1205.224673] Lustre: lustre-MDT0000-mdc-ffff8eb64621b000: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1215.467793] Lustre: lustre-MDT0001-mdc-ffff8eb64621b000: Connection to lustre-MDT0001 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1225.695223] Lustre: 26648:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786814039/real 1786814039] req@000000008d1f21d4 x1873610233878144/t0(0) o400->MGC192.168.206.127@tcp@192.168.206.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1786814055 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1225.732244] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 1228.746434] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1239.068787] Lustre: Unmounted lustre-client [ 1248.939054] Key type lgssc unregistered [ 1249.161138] LNet: 29345:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1249.178774] LNetError: 29345:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1249.192869] LNet: Removed LNI 192.168.206.27@tcp [ 1249.911549] Key type .llcrypt unregistered [ 1249.913673] Key type ._llcrypt unregistered [ 1261.284359] Key type ._llcrypt registered [ 1261.288554] Key type .llcrypt registered [ 1262.237260] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 13:14:50 (1786814090) [ 1263.725790] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1263.774055] alg: No test for adler32 (adler32-zlib) [ 1264.779940] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 1264.987732] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 1266.639268] Key type lgssc registered [ 1267.453305] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1300.833865] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1304.229738] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1317.537358] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1320.072755] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 1325.543377] Lustre: lustre-OST0000-osc-ffff8eb64678f000: Connection to lustre-OST0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1338.991938] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1349.334175] Lustre: Unmounted lustre-client [ 1373.366043] Key type lgssc unregistered [ 1373.565230] LNet: 32956:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1373.579203] LNetError: 32956:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1373.598310] LNet: Removed LNI 192.168.206.27@tcp [ 1374.199432] Key type .llcrypt unregistered [ 1374.201508] Key type ._llcrypt unregistered [ 1384.173788] Key type ._llcrypt registered [ 1384.175328] Key type .llcrypt registered [ 1385.121745] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 13:16:53 (1786814213) [ 1386.700965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1386.712524] alg: No test for adler32 (adler32-zlib) [ 1387.695569] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 1387.882410] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 1389.536725] Key type lgssc registered [ 1390.393505] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1428.916399] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1433.693684] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1455.114570] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1457.101500] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 1461.172865] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1465.456947] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1477.446806] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-1513h18m24s [ 1479.036391] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1482.735979] Lustre: lustre-MDT0000-mdc-ffff8eb651a95000: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1488.647817] LustreError: 36743:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8eb651a95000: can't stat MDS #0: rc = -19 [ 1490.148383] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1500.138288] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 1500.150683] Lustre: Evicted from MGS (at 192.168.206.127@tcp) after server handle changed from 0x7040c896c9691919 to 0x7040c896c9691bdc [ 1500.159242] Lustre: MGC192.168.206.127@tcp: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 1501.226798] LustreError: lustre-MDT0000-mdc-ffff8eb651a95000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1501.256118] Lustre: lustre-MDT0000-mdc-ffff8eb651a95000: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 1504.170537] Lustre: DEBUG MARKER: Wait for df (36743) ... [ 1505.984156] Lustre: DEBUG MARKER: done [ 1508.001033] Lustre: Unmounted lustre-client [ 1538.964754] Key type lgssc unregistered [ 1539.358632] LNet: 37763:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1539.371872] LNetError: 37763:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1539.398855] LNet: Removed LNI 192.168.206.27@tcp [ 1540.487708] Key type .llcrypt unregistered [ 1540.489366] Key type ._llcrypt unregistered [ 1574.440468] Key type ._llcrypt registered [ 1574.443176] Key type .llcrypt registered [ 1575.641920] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 13:20:03 (1786814403) [ 1578.276288] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1578.302779] alg: No test for adler32 (adler32-zlib) [ 1579.438171] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 1579.778331] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 1581.447258] Key type lgssc registered [ 1582.559771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1634.069175] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1640.574475] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1670.463418] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1673.274261] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 1678.085442] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-1513h21m45s [ 1679.985960] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1687.411526] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1690.494576] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1711.073139] Lustre: 41921:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786814520/real 1786814520] req@0000000073dea44e x1873610705738496/t0(0) o101->lustre-MDT0000-mdc-ffff8eb645eb9800@192.168.206.127@tcp:12/10 lens 576/1152 e 0 to 1 dl 1786814540 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1711.103918] Lustre: lustre-MDT0000-mdc-ffff8eb645eb9800: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1711.135352] Lustre: lustre-MDT0000-mdc-ffff8eb645eb9800: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 1732.886559] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1735.945189] Lustre: DEBUG MARKER: done [ 1739.956178] Lustre: Unmounted lustre-client [ 1763.803618] Key type lgssc unregistered [ 1764.125217] LNet: 42800:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1764.144709] LNetError: 42800:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1764.163716] LNet: Removed LNI 192.168.206.27@tcp [ 1764.908690] Key type .llcrypt unregistered [ 1764.911924] Key type ._llcrypt unregistered [ 1798.597517] Key type ._llcrypt registered [ 1798.601450] Key type .llcrypt registered [ 1799.809803] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 13:23:47 (1786814627) [ 1801.568361] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1803.686855] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 13:23:51 (1786814631) [ 1808.166948] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1808.321204] alg: No test for adler32 (adler32-zlib) [ 1809.662871] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 1810.121332] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 1811.951215] Key type lgssc registered [ 1813.267490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1843.368733] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 13:24:31 (1786814671) [ 1873.034687] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1877.758655] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1895.847995] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1898.427182] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 1901.787995] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1904.790738] Lustre: Unmounted lustre-client [ 1921.054919] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1967.375672] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1973.388994] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1973.858754] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 1977.035623] Lustre: Unmounted lustre-client [ 2033.198727] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2038.387314] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2039.120720] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 2043.493470] Lustre: Unmounted lustre-client [ 2062.928416] Lustre: DEBUG MARKER: files compared the same [ 2081.404995] Key type lgssc unregistered [ 2081.600992] LNet: 50427:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2081.608164] LNetError: 50427:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2081.620177] LNet: Removed LNI 192.168.206.27@tcp [ 2082.179658] Key type .llcrypt unregistered [ 2082.183079] Key type ._llcrypt unregistered [ 2093.458775] Key type ._llcrypt registered [ 2093.465977] Key type .llcrypt registered [ 2094.549463] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 13:28:42 (1786814922) [ 2096.395680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2096.418237] alg: No test for adler32 (adler32-zlib) [ 2097.460494] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 2097.679431] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2099.344985] Key type lgssc registered [ 2100.495388] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2148.229213] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2153.143915] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2173.283430] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2175.027784] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 2179.038943] Lustre: Unmounted lustre-client [ 2205.208279] Key type lgssc unregistered [ 2205.504740] LNet: 54012:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2205.521943] LNetError: 54012:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2205.548964] LNet: Removed LNI 192.168.206.27@tcp [ 2206.219131] Key type .llcrypt unregistered [ 2206.225088] Key type ._llcrypt unregistered [ 2219.930904] Key type ._llcrypt registered [ 2219.935098] Key type .llcrypt registered [ 2221.968845] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 13:30:49 (1786815049) [ 2225.259553] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2225.573152] alg: No test for adler32 (adler32-zlib) [ 2227.032162] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 2227.492691] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2229.343349] Key type lgssc registered [ 2231.450638] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2324.526405] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2363.525418] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2370.201858] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2399.214582] Key type lgssc unregistered [ 2399.567206] LNet: 57504:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2399.583122] LNetError: 57504:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2399.611333] LNet: Removed LNI 192.168.206.27@tcp [ 2400.609567] Key type .llcrypt unregistered [ 2400.616841] Key type ._llcrypt unregistered [ 2418.029286] Key type ._llcrypt registered [ 2418.032960] Key type .llcrypt registered [ 2419.693219] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 13:34:07 (1786815247) [ 2423.442853] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2423.495215] alg: No test for adler32 (adler32-zlib) [ 2424.607805] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 2424.885273] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2426.615215] Key type lgssc registered [ 2427.793665] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2507.307300] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 2514.388173] Lustre: Unmounted lustre-client [ 2552.094214] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 13:36:20 (1786815380) [ 2675.805556] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2696.233825] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 2703.895773] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2703.912628] LustreError: 63977:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8eb6583cd000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2703.950989] Lustre: 58493:0:(llite_lib.c:4340:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.206.127@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2714.303570] Lustre: Unmounted lustre-client [ 2767.632958] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 13:39:55 (1786815595) [ 2778.701667] Key type lgssc unregistered [ 2778.964152] LNet: 65193:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2778.970496] LNetError: 65193:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2778.987837] LNet: Removed LNI 192.168.206.27@tcp [ 2779.690284] Key type .llcrypt unregistered [ 2779.697061] Key type ._llcrypt unregistered [ 2791.988187] Key type ._llcrypt registered [ 2791.989342] Key type .llcrypt registered [ 2792.564494] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2792.578483] alg: No test for adler32 (adler32-zlib) [ 2793.609481] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 2793.864351] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2795.607249] Key type lgssc registered [ 2797.144060] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2848.857203] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2855.155733] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2877.953455] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2879.549897] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 2886.613613] Lustre: Unmounted lustre-client [ 2915.637436] Key type lgssc unregistered [ 2915.970723] LNet: 68216:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2915.984403] LNetError: 68216:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2916.007037] LNet: Removed LNI 192.168.206.27@tcp [ 2916.722689] Key type .llcrypt unregistered [ 2916.724427] Key type ._llcrypt unregistered [ 2927.750183] Key type ._llcrypt registered [ 2927.751986] Key type .llcrypt registered [ 2928.129791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2928.147798] alg: No test for adler32 (adler32-zlib) [ 2929.340380] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 2929.879910] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2931.727252] Key type lgssc registered [ 2933.440376] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3051.316407] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3057.909109] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3078.936278] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3080.601510] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 3083.033309] Lustre: Unmounted lustre-client [ 3109.427990] Key type lgssc unregistered [ 3109.654895] LNet: 71646:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3109.663993] LNetError: 71646:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3109.685398] LNet: Removed LNI 192.168.206.27@tcp [ 3110.352752] Key type .llcrypt unregistered [ 3110.355331] Key type ._llcrypt unregistered [ 3124.186024] Key type ._llcrypt registered [ 3124.187640] Key type .llcrypt registered [ 3125.449260] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 13:45:53 (1786815953) [ 3127.457420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3127.555098] alg: No test for adler32 (adler32-zlib) [ 3128.542615] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 3128.791346] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 3130.487144] Key type lgssc registered [ 3131.711898] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3179.644223] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3185.017412] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3203.297023] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3205.034092] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 3210.180451] Lustre: Unmounted lustre-client [ 3210.514348] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 3213.701539] Lustre: Unmounted lustre-client [ 3238.016344] Key type lgssc unregistered [ 3238.277222] LNet: 75324:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3238.286745] LNetError: 75324:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3238.303375] LNet: Removed LNI 192.168.206.27@tcp [ 3238.957734] Key type .llcrypt unregistered [ 3238.959910] Key type ._llcrypt unregistered [ 3250.547330] Key type ._llcrypt registered [ 3250.550394] Key type .llcrypt registered [ 3251.063266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3251.106307] alg: No test for adler32 (adler32-zlib) [ 3252.317303] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 3252.640454] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 3254.399181] Key type lgssc registered [ 3255.798226] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3303.620470] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3309.801516] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3329.547654] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3332.504187] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 3336.438847] Lustre: Unmounted lustre-client [ 3364.675898] Key type lgssc unregistered [ 3364.992760] LNet: 78357:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3365.003394] LNetError: 78357:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3365.031365] LNet: Removed LNI 192.168.206.27@tcp [ 3365.627576] Key type .llcrypt unregistered [ 3365.631599] Key type ._llcrypt unregistered [ 3381.285038] Key type ._llcrypt registered [ 3381.287871] Key type .llcrypt registered [ 3382.999738] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 13:50:10 (1786816210) [ 3385.693415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3385.708237] alg: No test for adler32 (adler32-zlib) [ 3386.749265] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 3386.921988] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 3388.567914] Key type lgssc registered [ 3389.680745] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3436.901066] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3442.289690] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3460.549261] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3462.342425] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 3471.032165] Lustre: lustre: root_squash is set to 0:0 [ 3471.061767] Lustre: lustre: nosquash_nids is cleared [ 3478.078393] Lustre: lustre: root_squash is set to 500:500 [ 3487.250572] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.206.27@tcp 192.168.0.[2,10]@tcp [ 3495.748051] Lustre: lustre: nosquash_nids set to 192.168.206.27/24@tcp [ 3501.331705] Lustre: Unmounted lustre-client [ 3527.416933] Key type lgssc unregistered [ 3527.825614] LNet: 82304:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3527.834716] LNetError: 82304:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3527.851586] LNet: Removed LNI 192.168.206.27@tcp [ 3528.666616] Key type .llcrypt unregistered [ 3528.670764] Key type ._llcrypt unregistered [ 3541.091571] Key type ._llcrypt registered [ 3541.097610] Key type .llcrypt registered [ 3542.257487] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 13:52:50 (1786816370) [ 3545.140777] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3546.649508] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 13:52:54 (1786816374) [ 3548.954420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3548.981279] alg: No test for adler32 (adler32-zlib) [ 3549.989245] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 3550.276302] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 3551.999180] Key type lgssc registered [ 3553.236135] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3599.951761] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3605.792485] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3625.661690] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3627.214073] Lustre: lustre: root_squash is set to 500:500 [ 3627.238361] Lustre: lustre: nosquash_nids set to 192.168.206.27/24@tcp [ 3628.302659] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 3633.564120] Lustre: Unmounted lustre-client [ 3660.197868] Key type lgssc unregistered [ 3660.445912] LNet: 86260:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3660.467636] LNetError: 86260:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3660.485492] LNet: Removed LNI 192.168.206.27@tcp [ 3661.280324] Key type .llcrypt unregistered [ 3661.282293] Key type ._llcrypt unregistered [ 3676.988589] Key type ._llcrypt registered [ 3676.995917] Key type .llcrypt registered [ 3678.181308] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3679.436364] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 13:55:07 (1786816507) [ 3695.934521] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3695.970316] alg: No test for adler32 (adler32-zlib) [ 3697.024398] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 3697.246656] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 3698.927254] Key type lgssc registered [ 3700.183034] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3776.596673] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3782.986643] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3807.161233] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3879.362252] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3883.951504] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3902.349425] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3912.738121] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 3945.575880] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8eb6533ee000.ost_server_uuid 50 [ 3947.090550] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8eb6533ee000.ost_server_uuid in IDLE state after 0 sec [ 3947.500761] LustreError: 87577:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 3947.558541] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 3951.542196] Lustre: Unmounted lustre-client [ 3954.191752] Lustre: Unmounted lustre-client [ 3993.898577] Key type lgssc unregistered [ 3994.135938] LNet: 93922:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3994.143593] LNetError: 93922:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3994.162206] LNet: Removed LNI 192.168.206.27@tcp [ 3994.774772] Key type .llcrypt unregistered [ 3994.778562] Key type ._llcrypt unregistered [ 4020.446134] Key type ._llcrypt registered [ 4020.448612] Key type .llcrypt registered [ 4021.604959] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 14:00:49 (1786816849) [ 4038.197902] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4038.244499] alg: No test for adler32 (adler32-zlib) [ 4039.251560] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 4039.502304] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 4041.167556] Key type lgssc registered [ 4042.376889] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4112.981235] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4118.088930] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4138.957135] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4140.486064] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 4162.020416] Lustre: 95298:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786816975/real 1786816975] req@00000000113f8469 x1873613284185216/t0(0) o400->lustre-OST0000-osc-ffff8eb647265800@192.168.206.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1786816991 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4162.060600] Lustre: lustre-OST0000-osc-ffff8eb647265800: Connection to lustre-OST0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4168.160778] Lustre: 95298:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786816980/real 1786816980] req@00000000bb6eb0d0 x1873613284185728/t0(0) o400->lustre-OST0000-osc-ffff8eb647265800@192.168.206.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1786816996 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4172.305633] Lustre: lustre-OST0000-osc-ffff8eb647265800: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 4173.279176] Lustre: 95298:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786816985/real 1786816985] req@00000000efe696e6 x1873613284186240/t0(0) o400->lustre-OST0000-osc-ffff8eb647265800@192.168.206.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1786817001 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4177.376039] Lustre: 95298:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786816990/real 1786816990] req@00000000bb713aa9 x1873613284186752/t0(0) o400->lustre-OST0000-osc-ffff8eb647265800@192.168.206.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1786817006 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4177.390114] Lustre: lustre-MDT0000-mdc-ffff8eb647265800: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4188.639314] Lustre: 98471:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786817002/real 1786817002] req@00000000d034cd28 x1873613284188800/t0(0) o101->MGC192.168.206.127@tcp@192.168.206.127@tcp:26/25 lens 328/344 e 0 to 1 dl 1786817018 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4188.679989] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 4188.695066] Lustre: 98471:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.206.127@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 4198.896237] Lustre: Evicted from MGS (at 192.168.206.127@tcp) after server handle changed from 0x87e7d69400571e9f to 0x87e7d6940057202e [ 4198.905794] Lustre: MGC192.168.206.127@tcp: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 4198.920157] Lustre: 98471:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.206.127@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 4207.000130] Lustre: lustre-MDT0000-mdc-ffff8eb647265800: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 4210.566654] Lustre: Unmounted lustre-client [ 4252.321646] Key type lgssc unregistered [ 4252.632792] LNet: 99602:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4252.647448] LNetError: 99602:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4252.675455] LNet: Removed LNI 192.168.206.27@tcp [ 4253.490814] Key type .llcrypt unregistered [ 4253.492532] Key type ._llcrypt unregistered [ 4265.716502] Key type ._llcrypt registered [ 4265.719495] Key type .llcrypt registered [ 4266.756211] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 14:04:54 (1786817094) [ 4281.202442] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4281.218665] alg: No test for adler32 (adler32-zlib) [ 4282.269814] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 4282.560268] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 4284.295175] Key type lgssc registered [ 4285.288798] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4317.158715] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4395.401976] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4400.073433] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4418.083846] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4419.530996] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 4421.277166] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4422.746398] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 14:07:30 (1786817250) [ 4648.232607] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4658.572024] Lustre: Unmounted lustre-client [ 4770.882604] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4777.326939] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4802.114857] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4850.332304] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:14:38 (1786817678) [ 4945.048953] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4950.029741] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4973.024881] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4973.700426] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 4978.191803] Lustre: Unmounted lustre-client [ 5007.133699] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:17:15 (1786817835) [ 5097.705464] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5102.863449] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5124.547890] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5125.213447] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 5128.978104] Lustre: Unmounted lustre-client [ 5154.854457] Key type lgssc unregistered [ 5155.135777] LNet: 122621:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5155.141753] LNetError: 122621:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5155.159097] LNet: Removed LNI 192.168.206.27@tcp [ 5155.777364] Key type .llcrypt unregistered [ 5155.779992] Key type ._llcrypt unregistered [ 5172.330534] Key type ._llcrypt registered [ 5172.332732] Key type .llcrypt registered [ 5173.406696] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 14:20:01 (1786818001) [ 5176.428312] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5176.489352] alg: No test for adler32 (adler32-zlib) [ 5177.572036] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 5177.874970] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 5179.567175] Key type lgssc registered [ 5181.244266] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5231.680767] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5237.125101] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5259.044820] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5260.816896] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 5274.425353] Lustre: Unmounted lustre-client [ 5302.023142] Key type lgssc unregistered [ 5302.243600] LNet: 126222:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5302.250469] LNetError: 126222:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5302.270682] LNet: Removed LNI 192.168.206.27@tcp [ 5302.867410] Key type .llcrypt unregistered [ 5302.869377] Key type ._llcrypt unregistered [ 5316.735972] Key type ._llcrypt registered [ 5316.741202] Key type .llcrypt registered [ 5318.205104] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 14:22:25 (1786818145) [ 5321.162757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5321.173691] alg: No test for adler32 (adler32-zlib) [ 5322.378646] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 5322.819305] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 5324.744081] Key type lgssc registered [ 5326.513524] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5379.348855] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5385.373565] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5405.465959] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5408.205036] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 5418.473097] Lustre: lustre-OST0000-osc-ffff8eb644423000: Connection to lustre-OST0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5437.685817] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8eb644423000.ost_server_uuid 50 [ 5439.423837] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5450.454244] Lustre: Unmounted lustre-client [ 5473.324484] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 14:25:01 (1786818301) [ 5504.579762] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5509.697784] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5527.222780] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5547.781498] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5548.231992] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 5553.641371] Lustre: lustre-OST0000-osc-ffff8eb645bfc800: Connection to lustre-OST0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5576.893352] Lustre: Unmounted lustre-client [ 5627.276804] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 14:27:35 (1786818455) [ 5656.566600] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5661.606903] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5681.697625] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5704.559795] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5705.231433] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 5710.316718] Lustre: lustre-OST0000-osc-ffff8eb646770800: Connection to lustre-OST0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5727.086392] Lustre: Unmounted lustre-client [ 5773.807411] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 14:30:01 (1786818601) [ 5858.182469] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5863.654950] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5885.521684] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5946.854546] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5953.125653] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5973.387752] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6005.022883] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 6046.056223] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6051.630727] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 4 sec [ 6053.876885] Lustre: Unmounted lustre-client [ 6093.509261] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 14:35:21 (1786818921) [ 6125.830142] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6130.728918] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6149.870156] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6180.864720] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6209.825024] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 6250.814660] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6252.431786] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6256.144131] Lustre: lustre-OST0001-osc-ffff8eb644421000: Connection to lustre-OST0001 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6272.427597] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6282.811952] Lustre: Unmounted lustre-client [ 6341.286815] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 14:39:29 (1786819169) [ 6373.592763] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6378.456439] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6395.506084] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6396.042146] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 6416.553195] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6418.078554] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6431.207407] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8eb651a4b000.ost_server_uuid 50 [ 6432.580426] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8eb651a4b000.ost_server_uuid in IDLE state after 0 sec [ 6435.281695] Lustre: Unmounted lustre-client [ 6435.493781] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6435.498155] Lustre: Skipped 3 previous similar messages [ 6435.579371] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 6436.408240] LustreError: 149676:0:(sec.c:403:import_sec_validate_get()) import 00000000f298e9aa (NEW) with no sec [ 6488.947684] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 14:41:56 (1786819316) [ 6521.048666] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6526.068542] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6543.218531] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6559.961243] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6560.601584] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6560.770445] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 6572.631501] Lustre: Unmounted lustre-client [ 6572.633271] Lustre: Skipped 1 previous similar message [ 6618.177909] Key type lgssc unregistered [ 6618.428418] LNet: 153075:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6618.441643] LNetError: 153075:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6618.482043] LNet: Removed LNI 192.168.206.27@tcp [ 6619.190571] Key type .llcrypt unregistered [ 6619.195800] Key type ._llcrypt unregistered [ 6633.953255] Key type ._llcrypt registered [ 6633.955031] Key type .llcrypt registered [ 6635.148993] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 14:44:23 (1786819463) [ 6636.388837] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6636.421258] alg: No test for adler32 (adler32-zlib) [ 6637.537494] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 6637.801714] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 6639.520539] Key type lgssc registered [ 6640.799946] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6689.943685] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6696.021264] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6714.777639] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6736.347705] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6737.864589] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6739.033649] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 6743.550497] LustreError: lustre-MDT0001-mdc-ffff8eb646777800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6743.569818] Lustre: lustre-MDT0001-mdc-ffff8eb646777800: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 6749.442795] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6751.091267] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6772.244385] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6775.690363] Lustre: Unmounted lustre-client [ 6798.277562] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 14:47:06 (1786819626) [ 6891.344507] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6897.108868] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6917.015688] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6917.686642] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 6942.888694] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6944.076627] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6947.307736] Lustre: lustre-OST0001-osc-ffff8eb647bba000: Connection to lustre-OST0001 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6955.471592] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6965.832746] Lustre: Unmounted lustre-client [ 7001.188918] Key type lgssc unregistered [ 7001.441249] LNet: 162679:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7001.458800] LNetError: 162679:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7001.474336] LNet: Removed LNI 192.168.206.27@tcp [ 7002.187227] Key type .llcrypt unregistered [ 7002.189354] Key type ._llcrypt unregistered [ 7032.796958] Key type ._llcrypt registered [ 7032.799221] Key type .llcrypt registered [ 7034.190364] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 14:51:01 (1786819861) [ 7036.746107] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7036.801891] alg: No test for adler32 (adler32-zlib) [ 7037.943256] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 7038.265414] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 7039.994757] Key type lgssc registered [ 7041.179109] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7093.125374] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7099.442746] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7119.524614] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7121.265037] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 7169.185532] Lustre: Unmounted lustre-client [ 7205.644080] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7206.130875] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 7242.263081] Lustre: Unmounted lustre-client [ 7270.360645] Key type lgssc unregistered [ 7270.709634] LNet: 167126:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7270.719807] LNetError: 167126:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7270.743956] LNet: Removed LNI 192.168.206.27@tcp [ 7271.485397] Key type .llcrypt unregistered [ 7271.490344] Key type ._llcrypt unregistered [ 7286.283993] Key type ._llcrypt registered [ 7286.286488] Key type .llcrypt registered [ 7287.352989] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 14:55:15 (1786820115) [ 7289.634199] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7289.655396] alg: No test for adler32 (adler32-zlib) [ 7290.648907] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 7290.978985] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 7292.720392] Key type lgssc registered [ 7294.058286] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7344.412684] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7351.964036] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7371.618452] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7374.460908] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 7396.424176] Lustre: Unmounted lustre-client [ 7424.238122] Key type lgssc unregistered [ 7424.452287] LNet: 170879:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7424.465608] LNetError: 170879:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7424.485836] LNet: Removed LNI 192.168.206.27@tcp [ 7425.083596] Key type .llcrypt unregistered [ 7425.085684] Key type ._llcrypt unregistered [ 7436.595645] Key type ._llcrypt registered [ 7436.597509] Key type .llcrypt registered [ 7437.024780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7437.053671] alg: No test for adler32 (adler32-zlib) [ 7438.153112] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 7438.439731] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 7440.079174] Key type lgssc registered [ 7441.026255] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7486.618655] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7491.749578] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7510.566844] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7512.304600] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 7517.991467] Lustre: Unmounted lustre-client [ 7543.145299] Key type lgssc unregistered [ 7543.328851] LNet: 173942:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7543.340262] LNetError: 173942:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7543.359549] LNet: Removed LNI 192.168.206.27@tcp [ 7543.941626] Key type .llcrypt unregistered [ 7543.943747] Key type ._llcrypt unregistered [ 7557.917618] Key type ._llcrypt registered [ 7557.925632] Key type .llcrypt registered [ 7559.053461] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 14:59:47 (1786820387) [ 7561.582359] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7561.705074] alg: No test for adler32 (adler32-zlib) [ 7562.731698] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 7562.953659] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 7564.599657] Key type lgssc registered [ 7565.670547] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7606.852806] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7611.985287] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7629.851851] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7632.827630] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 7651.149455] Lustre: Unmounted lustre-client [ 7677.806403] Key type lgssc unregistered [ 7678.008062] LNet: 177702:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7678.013914] LNetError: 177702:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7678.044705] LNet: Removed LNI 192.168.206.27@tcp [ 7678.609214] Key type .llcrypt unregistered [ 7678.611551] Key type ._llcrypt unregistered [ 7689.123089] Key type ._llcrypt registered [ 7689.130457] Key type .llcrypt registered [ 7689.697542] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7689.712991] alg: No test for adler32 (adler32-zlib) [ 7690.709577] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 7690.949681] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 7692.591215] Key type lgssc registered [ 7693.560517] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7733.366855] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7738.246111] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7758.618163] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7760.141489] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 7767.447149] Lustre: Unmounted lustre-client [ 7794.249653] Key type lgssc unregistered [ 7794.481150] LNet: 180766:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7794.500418] LNetError: 180766:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7794.534405] LNet: Removed LNI 192.168.206.27@tcp [ 7795.228242] Key type .llcrypt unregistered [ 7795.231293] Key type ._llcrypt unregistered [ 7809.627237] Key type ._llcrypt registered [ 7809.630133] Key type .llcrypt registered [ 7810.740649] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 15:03:58 (1786820638) [ 7831.388782] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7831.425209] alg: No test for adler32 (adler32-zlib) [ 7832.419692] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 7832.610109] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 7834.303123] Key type lgssc registered [ 7835.433895] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7906.359822] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7911.223678] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7932.527812] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7969.428801] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 15:06:37 (1786820797) [ 7996.913146] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8002.253492] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8019.462472] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8020.968691] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 8025.354147] Lustre: Unmounted lustre-client [ 8049.607095] Key type lgssc unregistered [ 8049.817727] LNet: 187857:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8049.824647] LNetError: 187857:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8049.850580] LNet: Removed LNI 192.168.206.27@tcp [ 8050.590406] Key type .llcrypt unregistered [ 8050.594836] Key type ._llcrypt unregistered [ 8063.170490] Key type ._llcrypt registered [ 8063.174040] Key type .llcrypt registered [ 8064.261754] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8065.691527] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 15:08:13 (1786820893) [ 8082.228563] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8082.251205] alg: No test for adler32 (adler32-zlib) [ 8083.306325] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 8083.559663] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 8085.239178] Key type lgssc registered [ 8086.247400] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8167.657265] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8172.096469] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8190.053358] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8208.055738] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8210.730799] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 8229.515941] Lustre: Unmounted lustre-client [ 8354.054281] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8359.143503] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8380.070666] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8420.412423] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 15:14:08 (1786821248) [ 8421.834235] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8423.573604] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 15:14:11 (1786821251) [ 8513.918821] Key type lgssc unregistered [ 8514.293966] LNet: 199286:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8514.309343] LNetError: 199286:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8514.329559] LNet: Removed LNI 192.168.206.27@tcp [ 8515.031262] Key type .llcrypt unregistered [ 8515.035896] Key type ._llcrypt unregistered [ 8530.236389] Key type ._llcrypt registered [ 8530.239331] Key type .llcrypt registered [ 8531.617188] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 15:15:59 (1786821359) [ 8571.215754] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8571.238277] alg: No test for adler32 (adler32-zlib) [ 8572.302921] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 8572.541996] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 8574.287263] Key type lgssc registered [ 8575.407673] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8613.969730] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8618.866499] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8648.917328] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8676.575062] Key type lgssc unregistered [ 8676.809462] LNet: 203024:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8676.819686] LNetError: 203024:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8676.834582] LNet: Removed LNI 192.168.206.27@tcp [ 8677.494758] Key type .llcrypt unregistered [ 8677.499764] Key type ._llcrypt unregistered [ 8690.898186] Key type ._llcrypt registered [ 8690.900191] Key type .llcrypt registered [ 8692.002234] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 15:18:40 (1786821520) [ 8694.610024] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8694.632113] alg: No test for adler32 (adler32-zlib) [ 8695.647360] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 8695.879893] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 8697.559652] Key type lgssc registered [ 8698.689120] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8743.118769] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8747.774921] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8770.041386] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8772.846451] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 8783.343254] Lustre: lustre-MDT0000-mdc-ffff8eb65310a800: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8793.573692] Lustre: lustre-MDT0001-mdc-ffff8eb65310a800: Connection to lustre-MDT0001 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8803.809356] Lustre: 204014:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786821617/real 1786821617] req@00000000471c6ad6 x1873618167480448/t0(0) o400->MGC192.168.206.127@tcp@192.168.206.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1786821633 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8803.825900] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 8814.117788] Lustre: Evicted from MGS (at 192.168.206.127@tcp) after server handle changed from 0xa4dfb7ed5fbbe668 to 0xa4dfb7ed5fbbffac [ 8814.128559] Lustre: MGC192.168.206.127@tcp: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 8834.535952] LustreError: lustre-MDT0000-mdc-ffff8eb65310a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8834.558325] Lustre: lustre-MDT0000-mdc-ffff8eb65310a800: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 8835.728909] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8837.009930] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8841.858676] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8843.360323] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8846.110481] Lustre: Unmounted lustre-client [ 8882.246244] Key type lgssc unregistered [ 8882.527684] LNet: 207974:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8882.546032] LNetError: 207974:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8882.572825] LNet: Removed LNI 192.168.206.27@tcp [ 8883.328706] Key type .llcrypt unregistered [ 8883.330711] Key type ._llcrypt unregistered [ 8898.929743] Key type ._llcrypt registered [ 8898.933827] Key type .llcrypt registered [ 8900.199872] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 15:22:08 (1786821728) [ 8902.579725] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8902.614183] alg: No test for adler32 (adler32-zlib) [ 8903.788565] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 8904.229197] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 8905.969753] Key type lgssc registered [ 8907.770510] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8956.753793] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8961.895832] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9014.712149] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9019.969130] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9052.706311] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9076.860554] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9125.525302] Key type lgssc unregistered [ 9125.928515] LNet: 213273:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9125.943519] LNetError: 213273:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9125.964960] LNet: Removed LNI 192.168.206.27@tcp [ 9126.803581] Key type .llcrypt unregistered [ 9126.823713] Key type ._llcrypt unregistered [ 9169.717707] Key type ._llcrypt registered [ 9169.721632] Key type .llcrypt registered [ 9171.177582] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 15:26:38 (1786821998) [ 9208.932284] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9208.953609] alg: No test for adler32 (adler32-zlib) [ 9210.095109] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 9210.343415] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 9212.071224] Key type lgssc registered [ 9213.115969] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9283.413973] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9288.796279] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9308.593701] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9346.526361] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 15:29:34 (1786822174) [ 9353.369504] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 15:29:41 (1786822181) [ 9385.614600] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9390.399711] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9407.251287] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9408.792839] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 9413.738617] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9415.406328] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9416.806844] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9418.141103] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9424.360501] Lustre: lustre-MDT0000-mdc-ffff8eb647488000: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9439.711703] Lustre: 215167:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786822253/real 1786822253] req@000000007fc89517 x1873618706376192/t0(0) o400->MGC192.168.206.127@tcp@192.168.206.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1786822269 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9439.719151] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 9439.772356] Lustre: Evicted from MGS (at 192.168.206.127@tcp) after server handle changed from 0xe94de6d562a2322e to 0xe94de6d562a2349d [ 9439.783566] Lustre: MGC192.168.206.127@tcp: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 9443.253972] Lustre: lustre-MDT0000-mdc-ffff8eb647488000: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 9448.968387] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9450.192503] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9452.982934] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9455.082620] Lustre: lustre-MDT0000-mdc-ffff8eb647488000: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9471.455351] Lustre: 215169:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786822284/real 1786822284] req@000000009d57a3f3 x1873618706383104/t0(0) o400->MGC192.168.206.127@tcp@192.168.206.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1786822300 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9471.481996] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 9477.520309] Lustre: lustre-MDT0000-mdc-ffff8eb647488000: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 9481.645101] Lustre: Evicted from MGS (at 192.168.206.127@tcp) after server handle changed from 0xe94de6d562a2349d to 0xe94de6d562a2394a [ 9481.659263] Lustre: MGC192.168.206.127@tcp: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 9483.077125] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9484.185367] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9487.351452] Lustre: Unmounted lustre-client [ 9544.497193] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9549.755493] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9572.271339] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9573.183155] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 9576.952327] Lustre: Unmounted lustre-client [ 9603.606771] Key type lgssc unregistered [ 9603.820557] LNet: 225734:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9603.825516] LNetError: 225734:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9603.837864] LNet: Removed LNI 192.168.206.27@tcp [ 9604.449324] Key type .llcrypt unregistered [ 9604.453465] Key type ._llcrypt unregistered [ 9617.317660] Key type ._llcrypt registered [ 9617.321418] Key type .llcrypt registered [ 9618.514206] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 15:34:06 (1786822446) [ 9622.189474] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9622.271197] alg: No test for adler32 (adler32-zlib) [ 9623.268683] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 9623.446652] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 9625.095201] Key type lgssc registered [ 9626.188259] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9668.859791] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9673.361334] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9691.705402] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9694.182810] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 9699.325594] Lustre: Unmounted lustre-client [ 9758.551447] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9764.108687] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9784.159408] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9790.015487] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 9798.865872] Lustre: Unmounted lustre-client [ 9865.183816] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9871.049216] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9890.527857] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9891.180640] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [ 9892.582036] Lustre: Unmounted lustre-client [ 9927.624532] Key type lgssc unregistered [ 9927.871411] LNet: 232778:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9927.889970] LNetError: 232778:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9927.911074] LNet: Removed LNI 192.168.206.27@tcp [ 9928.453655] Key type .llcrypt unregistered [ 9928.455284] Key type ._llcrypt unregistered [ 9943.877781] Key type ._llcrypt registered [ 9943.881116] Key type .llcrypt registered [ 9945.562786] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 15:39:33 (1786822773) [ 9949.307553] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9949.461687] alg: No test for adler32 (adler32-zlib) [ 9950.626301] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [ 9951.018047] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 9952.848444] Key type lgssc registered [ 9954.245523] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9994.546579] Key type lgssc unregistered [ 9994.818559] LNet: 235102:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9994.826674] LNetError: 235102:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9994.843226] LNet: Removed LNI 192.168.206.27@tcp [ 9995.334233] Key type .llcrypt unregistered [ 9995.336511] Key type ._llcrypt unregistered [10022.788231] Key type ._llcrypt registered [10022.801087] Key type .llcrypt registered [10023.208217] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10023.224868] alg: No test for adler32 (adler32-zlib) [10024.407837] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [10024.852077] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [10026.559296] Key type lgssc registered [10028.186564] Lustre: Echo OBD driver; http://www.lustre.org/ [10106.994523] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10112.509748] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10135.712804] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10183.607279] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10185.653510] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 15:43:33 (1786823013) [10217.072523] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10222.824165] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10244.869342] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10266.320924] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10268.016895] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [10273.260532] Lustre: lustre-OST0001-osc-ffff8eb643b7e800: Connection to lustre-OST0001 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [10280.085152] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10290.418426] Lustre: Unmounted lustre-client [10322.306373] Key type lgssc unregistered [10322.569669] LNet: 242224:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10322.579417] LNetError: 242224:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10322.597741] LNet: Removed LNI 192.168.206.27@tcp [10323.257131] Key type .llcrypt unregistered [10323.266552] Key type ._llcrypt unregistered [10353.590975] Key type ._llcrypt registered [10353.594887] Key type .llcrypt registered [10355.251096] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 15:46:22 (1786823182) [10369.628669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10369.652794] alg: No test for adler32 (adler32-zlib) [10370.785218] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [10371.108991] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [10372.863900] Key type lgssc registered [10374.186587] Lustre: Echo OBD driver; http://www.lustre.org/ [10420.141619] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10427.613423] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10459.065379] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 15:48:06 (1786823286) [10493.770410] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10499.092641] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10517.206567] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10518.800138] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [10525.621035] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [10537.352529] Lustre: Unmounted lustre-client [10632.685859] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10637.873984] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10687.360047] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10687.942300] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [10690.186497] Lustre: Unmounted lustre-client [10718.430467] Key type lgssc unregistered [10718.660779] LNet: 249945:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10718.674063] LNetError: 249945:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10718.686375] LNet: Removed LNI 192.168.206.27@tcp [10719.457670] Key type .llcrypt unregistered [10719.462629] Key type ._llcrypt unregistered [10748.018351] Key type ._llcrypt registered [10748.024413] Key type .llcrypt registered [10748.452451] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10748.469807] alg: No test for adler32 (adler32-zlib) [10749.593424] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [10749.876450] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [10751.591216] Key type lgssc registered [10753.028025] Lustre: Echo OBD driver; http://www.lustre.org/ [10837.130927] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10843.025494] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10869.290664] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10927.522838] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [10929.096706] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 15:55:57 (1786823757) [10959.322991] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10964.461803] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10986.465984] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10990.894221] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [10993.852749] Lustre: Unmounted lustre-client [11028.719100] Key type lgssc unregistered [11028.961688] LNet: 256591:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11028.968062] LNetError: 256591:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11028.983184] LNet: Removed LNI 192.168.206.27@tcp [11029.683217] Key type .llcrypt unregistered [11029.691223] Key type ._llcrypt unregistered [11045.263503] Key type ._llcrypt registered [11045.267166] Key type .llcrypt registered [11046.702723] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11048.711446] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 15:57:56 (1786823876) [11058.170593] Key type .llcrypt unregistered [11058.173160] Key type ._llcrypt unregistered [11067.516630] Key type ._llcrypt registered [11067.531958] Key type .llcrypt registered [11067.786795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11067.804579] alg: No test for adler32 (adler32-zlib) [11068.873820] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [11069.112939] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [11070.791468] Key type lgssc registered [11071.979649] Lustre: Echo OBD driver; http://www.lustre.org/ [11121.972966] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11137.976210] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [11140.371740] Lustre: Unmounted lustre-client [11168.118806] Key type lgssc unregistered [11168.345611] LNet: 260055:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11168.349531] LNetError: 260055:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11168.360447] LNet: Removed LNI 192.168.206.27@tcp [11169.042335] Key type .llcrypt unregistered [11169.045131] Key type ._llcrypt unregistered [11185.456647] Key type ._llcrypt registered [11185.460289] Key type .llcrypt registered [11187.033239] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 16:00:14 (1786824014) [11189.953705] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11189.992582] alg: No test for adler32 (adler32-zlib) [11191.063490] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [11191.310350] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [11192.967538] Key type lgssc registered [11194.130626] Lustre: Echo OBD driver; http://www.lustre.org/ [11279.408962] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11313.300238] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11319.097994] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11320.761369] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [11323.206090] Lustre: Unmounted lustre-client [11350.923934] Key type lgssc unregistered [11351.191079] LNet: 263616:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11351.194717] LNetError: 263616:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11351.226874] LNet: Removed LNI 192.168.206.27@tcp [11351.940079] Key type .llcrypt unregistered [11351.948208] Key type ._llcrypt unregistered [11368.694461] Key type ._llcrypt registered [11368.696966] Key type .llcrypt registered [11369.979537] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 16:03:17 (1786824197) [11372.530275] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11372.677185] alg: No test for adler32 (adler32-zlib) [11374.012935] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [11374.528213] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [11376.327469] Key type lgssc registered [11378.370376] Lustre: Echo OBD driver; http://www.lustre.org/ [11435.988547] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11442.468247] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11465.672728] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11467.623896] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [11472.885207] Lustre: lustre-MDT0000-mdc-ffff8eb651a07800: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [11478.480590] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11478.502951] LustreError: 266781:0:(file.c:6156:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [11478.897810] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [11478.956732] Lustre: Unmounted lustre-client [11498.886664] Key type lgssc unregistered [11499.204110] LNet: 267198:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11499.220100] LNetError: 267198:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11499.241951] LNet: Removed LNI 192.168.206.27@tcp [11500.073460] Key type .llcrypt unregistered [11500.079305] Key type ._llcrypt unregistered [11516.684439] Key type ._llcrypt registered [11516.686817] Key type .llcrypt registered [11518.590272] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 16:05:45 (1786824345) [11520.663497] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11520.692649] alg: No test for adler32 (adler32-zlib) [11521.810738] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [11522.140883] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [11523.865991] Key type lgssc registered [11525.331947] Lustre: Echo OBD driver; http://www.lustre.org/ [11577.529064] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11583.763675] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11604.064994] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11605.837967] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [11616.347258] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [11618.298814] Lustre: Unmounted lustre-client [11643.814945] Key type lgssc unregistered [11644.149709] LNet: 270783:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11644.170309] LNetError: 270783:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11644.199535] LNet: Removed LNI 192.168.206.27@tcp [11645.280985] Key type .llcrypt unregistered [11645.284498] Key type ._llcrypt unregistered [11662.193785] Key type ._llcrypt registered [11662.197498] Key type .llcrypt registered [11663.483830] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 16:08:11 (1786824491) [11681.331485] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11681.455948] alg: No test for adler32 (adler32-zlib) [11682.548856] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [11682.847662] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [11684.535193] Key type lgssc registered [11685.910451] Lustre: Echo OBD driver; http://www.lustre.org/ [11792.220193] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11794.156522] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [11834.890320] Lustre: lustre-MDT0001-mdc-ffff8eb653108000: Connection to lustre-MDT0001 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [11845.710276] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11856.016986] Lustre: Unmounted lustre-client [11890.746590] Key type lgssc unregistered [11890.999308] LNet: 276570:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11891.012946] LNetError: 276570:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11891.040523] LNet: Removed LNI 192.168.206.27@tcp [11891.578198] Key type .llcrypt unregistered [11891.580742] Key type ._llcrypt unregistered [11908.622971] Key type ._llcrypt registered [11908.626422] Key type .llcrypt registered [11910.143246] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 16:12:17 (1786824737) [11912.147600] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [11914.378278] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 16:12:21 (1786824741) [11915.913901] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [11917.675898] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 16:12:25 (1786824745) [11919.533886] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [11921.060714] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 16:12:29 (1786824749) [11922.557201] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [11924.026704] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 16:12:32 (1786824752) [11925.606516] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [11927.394200] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 16:12:35 (1786824755) [11937.475418] Key type .llcrypt unregistered [11937.484801] Key type ._llcrypt unregistered [11946.016888] Key type ._llcrypt registered [11946.019639] Key type .llcrypt registered [11947.094238] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11947.125529] alg: No test for adler32 (adler32-zlib) [11948.228890] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [11948.519220] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [11950.271469] Key type lgssc registered [11951.399773] Lustre: Echo OBD driver; http://www.lustre.org/ [12017.933954] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12023.033793] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12046.591411] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12048.228023] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [12051.874341] Lustre: Unmounted lustre-client [12081.305568] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 16:15:09 (1786824909) [12117.502385] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12123.955946] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12157.538348] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12163.615885] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [12165.911052] Lustre: Unmounted lustre-client [12211.539727] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 16:17:19 (1786825039) [12211.747179] LNet: 1 local NIs in recovery (showing 1): 192.168.206.27@tcp [12854.171943] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12859.563420] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12897.597215] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13003.255789] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 16:30:30 (1786825830) [13018.123272] Key type lgssc unregistered [13018.348407] LNet: 290104:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13018.360881] LNetError: 290104:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13018.385492] LNet: Removed LNI 192.168.206.27@tcp [13019.127595] Key type .llcrypt unregistered [13019.130456] Key type ._llcrypt unregistered [13146.183443] Key type ._llcrypt registered [13146.207722] Key type .llcrypt registered [13146.689511] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13146.712433] alg: No test for adler32 (adler32-zlib) [13147.741560] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [13147.939624] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [13149.583183] Key type lgssc registered [13150.882449] Lustre: Echo OBD driver; http://www.lustre.org/ [13239.131872] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13244.615515] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13266.658987] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13307.858284] Key type lgssc unregistered [13308.158383] LNet: 293809:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13308.169169] LNetError: 293809:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13308.190619] LNet: Removed LNI 192.168.206.27@tcp [13309.082942] Key type .llcrypt unregistered [13309.086490] Key type ._llcrypt unregistered [13347.971585] Key type ._llcrypt registered [13347.973843] Key type .llcrypt registered [13349.183775] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 16:36:17 (1786826177) [13370.298687] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13370.366578] alg: No test for adler32 (adler32-zlib) [13371.567770] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [13371.851551] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [13373.599176] Key type lgssc registered [13375.095230] Lustre: Echo OBD driver; http://www.lustre.org/ [13425.717433] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 16:37:33 (1786826253) [13437.642645] Key type lgssc unregistered [13437.866718] LNet: 297368:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13437.874973] LNetError: 297368:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13437.888412] LNet: Removed LNI 192.168.206.27@tcp [13438.482700] Key type .llcrypt unregistered [13438.491643] Key type ._llcrypt unregistered [13447.530315] Key type ._llcrypt registered [13447.545019] Key type .llcrypt registered [13447.921724] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13447.936242] alg: No test for adler32 (adler32-zlib) [13449.018683] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [13449.237916] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [13450.887190] Key type lgssc registered [13452.145815] Lustre: Echo OBD driver; http://www.lustre.org/ [13504.705542] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13509.626981] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13530.831880] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13793.165935] LNet: Added LNI 192.168.206.27@tcp50 [8/256/0/180] [13795.813329] LNet: Removed LNI 192.168.206.27@tcp [13797.805970] Lustre: 301775:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff8eb6450b2000: config has no valid NIDs, force dynamic NIDs [13799.008955] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [13799.228165] Lustre: Unmounted lustre-client [13801.625610] Key type lgssc unregistered [13802.014808] LNet: 302036:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13802.020613] LNetError: 302036:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13802.044584] LNet: Removed LNI 192.168.206.27@tcp50 [13802.653838] Key type .llcrypt unregistered [13802.660089] Key type ._llcrypt unregistered [13828.908500] Key type ._llcrypt registered [13828.910241] Key type .llcrypt registered [13829.470210] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13829.488921] alg: No test for adler32 (adler32-zlib) [13830.648277] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [13831.018874] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [13832.791331] Key type lgssc registered [13834.494931] Lustre: Echo OBD driver; http://www.lustre.org/ [13894.312467] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13899.708629] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13906.846911] LNet: Added LNI 192.168.206.27@tcp50 [8/256/0/180] [13907.626126] LNet: Removed LNI 192.168.206.27@tcp [13908.779482] Lustre: 304732:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff8eb647360800: config has no valid NIDs, force dynamic NIDs [13909.999698] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [13910.805185] Lustre: Unmounted lustre-client [13952.017839] Key type lgssc unregistered [13952.515842] LNet: 305608:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13952.532745] LNetError: 305608:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13952.568216] LNet: Removed LNI 192.168.206.27@tcp50 [13953.496219] Key type .llcrypt unregistered [13953.502910] Key type ._llcrypt unregistered [13965.152559] Key type ._llcrypt registered [13965.163135] Key type .llcrypt registered [13966.858114] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 16:46:34 (1786826794) [13986.616255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13986.656523] alg: No test for adler32 (adler32-zlib) [13987.842131] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [13988.208474] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [13989.919188] Key type lgssc registered [13991.927377] Lustre: Echo OBD driver; http://www.lustre.org/ [14068.593868] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14074.752319] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14096.443193] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14128.710602] Key type lgssc unregistered [14128.948576] LNet: 310359:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14128.956900] LNetError: 310359:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14128.978211] LNet: Removed LNI 192.168.206.27@tcp [14129.678299] Key type .llcrypt unregistered [14129.681954] Key type ._llcrypt unregistered [14138.668627] Key type ._llcrypt registered [14138.670337] Key type .llcrypt registered [14139.888275] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 16:49:27 (1786826967) [14142.180287] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14142.194072] alg: No test for adler32 (adler32-zlib) [14143.195077] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [14143.390466] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [14145.063207] Key type lgssc registered [14146.094832] Lustre: Echo OBD driver; http://www.lustre.org/ [14189.129437] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14194.159189] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14213.180602] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14216.092820] Lustre: Mounted lustre-client - version 2.17.57_1_g2c0d074 [14272.904187] Lustre: Mounted fs15246-client - version 2.17.57_1_g2c0d074 [14288.366420] Lustre: Unmounted fs15246-client [14313.617220] Lustre: Unmounted lustre-client [14347.781550] Key type lgssc unregistered [14347.963625] LNet: 315224:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14347.973132] LNetError: 315224:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14347.991685] LNet: Removed LNI 192.168.206.27@tcp [14348.530335] Key type .llcrypt unregistered [14348.532496] Key type ._llcrypt unregistered [14358.954776] Key type ._llcrypt registered [14358.959629] Key type .llcrypt registered [14360.242857] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 16:53:08 (1786827188) [14361.767933] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14361.823497] alg: No test for adler32 (adler32-zlib) [14362.844547] Lustre: Lustre: Build Version: 2.17.57_1_g2c0d074 [14363.074084] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [14364.839266] Key type lgssc registered [14366.209220] Lustre: Echo OBD driver; http://www.lustre.org/ [14487.995467] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14493.147607] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14514.725106] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14614.385283] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14341 sec ========= 16:57:22 (1786827442) [14616.078925] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 16:57:23 (1786827443) === [14619.282204] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 16:57:27 (1786827447) === [14651.614278] Key type lgssc unregistered [14651.855874] LNet: 324495:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14651.871655] LNetError: 324495:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14651.889856] LNet: Removed LNI 192.168.206.27@tcp [14652.484904] Key type .llcrypt unregistered [14652.487752] Key type ._llcrypt unregistered