[ 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 462631854 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 2560MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.002354] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004013] kvm-guest: setup PV IPIs [ 0.007302] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009016] pid_max: default: 32768 minimum: 301 [ 0.010143] LSM: Security Framework initializing [ 0.011048] Yama: becoming mindful. [ 0.012038] SELinux: Initializing. [ 0.013082] *** VALIDATE selinux *** [ 0.021789] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026701] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027169] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028105] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029115] *** VALIDATE tmpfs *** [ 0.030457] *** VALIDATE proc *** [ 0.032137] *** VALIDATE cgroup *** [ 0.033008] *** VALIDATE cgroup2 *** [ 0.034276] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035156] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037030] Spectre V2 : User space: Vulnerable [ 0.038011] Speculative Store Bypass: Vulnerable [ 0.040766] debug: unmapping init [mem 0xffffffffba059000-0xffffffffba060fff] [ 0.043144] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044699] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045027] ... version: 2 [ 0.046010] ... bit width: 48 [ 0.047011] ... generic registers: 4 [ 0.048012] ... value mask: 0000ffffffffffff [ 0.049013] ... max period: 00007fffffffffff [ 0.050012] ... fixed-purpose events: 3 [ 0.051010] ... event mask: 000000070000000f [ 0.052340] rcu: Hierarchical SRCU implementation. [ 0.054433] smp: Bringing up secondary CPUs ... [ 0.055593] x86: Booting SMP configuration: [ 0.056028] .... node #0, CPUs: #1 #2 #3 [ 0.061069] smp: Brought up 1 node, 4 CPUs [ 0.063011] smpboot: Max logical packages: 1 [ 0.064017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.126135] node 0 deferred pages initialised in 60ms [ 0.130011] devtmpfs: initialized [ 0.131219] x86/mm: Memory block size: 128MB [ 0.133895] gcov: version magic: 0x41383552 [ 0.135293] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.136063] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.137229] pinctrl core: initialized pinctrl subsystem [ 0.138191] [ 0.138499] ************************************************************* [ 0.139009] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.140010] ** ** [ 0.141016] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.142007] ** ** [ 0.143011] ** This means that this kernel is built to expose internal ** [ 0.144008] ** IOMMU data structures, which may compromise security on ** [ 0.145011] ** your system. ** [ 0.146010] ** ** [ 0.147009] ** If you see this message and you are not debugging the ** [ 0.148008] ** kernel, report this immediately to your vendor! ** [ 0.149008] ** ** [ 0.150008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.151008] ************************************************************* [ 0.152834] NET: Registered protocol family 16 [ 0.153362] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.154052] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.155050] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.158016] cpuidle: using governor menu [ 0.159862] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.162535] PCI: Using configuration type 1 for base access [ 0.164129] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.177083] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.179018] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.183079] cryptd: max_cpu_qlen set to 1000 [ 0.187305] ACPI: Added _OSI(Module Device) [ 0.189011] ACPI: Added _OSI(Processor Device) [ 0.190011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.191011] ACPI: Added _OSI(Processor Aggregator Device) [ 0.196811] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.204597] ACPI: Interpreter enabled [ 0.205061] ACPI: PM: (supports S0 S3 S4 S5) [ 0.206014] ACPI: Using IOAPIC for interrupt routing [ 0.207121] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.208391] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.218118] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.219043] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.220019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.221095] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.223376] acpiphp: Slot [2] registered [ 0.224106] acpiphp: Slot [5] registered [ 0.225102] acpiphp: Slot [6] registered [ 0.226078] acpiphp: Slot [3] registered [ 0.227000] acpiphp: Slot [4] registered [ 0.227101] acpiphp: Slot [7] registered [ 0.228132] acpiphp: Slot [8] registered [ 0.230095] acpiphp: Slot [9] registered [ 0.231103] acpiphp: Slot [10] registered [ 0.233168] acpiphp: Slot [11] registered [ 0.234121] acpiphp: Slot [12] registered [ 0.236098] acpiphp: Slot [13] registered [ 0.237103] acpiphp: Slot [14] registered [ 0.239092] acpiphp: Slot [15] registered [ 0.240093] acpiphp: Slot [16] registered [ 0.242093] acpiphp: Slot [17] registered [ 0.243083] acpiphp: Slot [18] registered [ 0.245126] acpiphp: Slot [19] registered [ 0.247076] acpiphp: Slot [20] registered [ 0.248080] acpiphp: Slot [21] registered [ 0.249078] acpiphp: Slot [22] registered [ 0.250099] acpiphp: Slot [23] registered [ 0.252150] acpiphp: Slot [24] registered [ 0.253105] acpiphp: Slot [25] registered [ 0.255117] acpiphp: Slot [26] registered [ 0.257231] acpiphp: Slot [27] registered [ 0.259239] acpiphp: Slot [28] registered [ 0.261258] acpiphp: Slot [29] registered [ 0.262082] acpiphp: Slot [30] registered [ 0.264077] acpiphp: Slot [31] registered [ 0.265073] PCI host bridge to bus 0000:00 [ 0.266015] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.268023] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.270022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.273024] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.277024] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.280025] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.282174] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.285548] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.288153] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.294609] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.298056] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.300013] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.303033] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.305017] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.307631] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.310725] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.313035] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.314761] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.318782] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.327009] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.333016] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.337763] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.347019] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.355018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.395020] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.432322] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.443016] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.455016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.484017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.495144] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.497232] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.498244] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.500231] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.501123] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.504055] iommu: Default domain type: Passthrough [ 0.506369] SCSI subsystem initialized [ 0.508103] ACPI: bus type USB registered [ 0.509101] usbcore: registered new interface driver usbfs [ 0.510080] usbcore: registered new interface driver hub [ 0.512061] usbcore: registered new device driver usb [ 0.513170] pps_core: LinuxPPS API ver. 1 registered [ 0.515011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.517053] PTP clock support registered [ 0.519199] EDAC MC: Ver: 3.0.0 [ 0.521011] PCI: Using ACPI for IRQ routing [ 0.523066] NetLabel: Initializing [ 0.524010] NetLabel: domain hash size = 128 [ 0.525009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.527076] NetLabel: unlabeled traffic allowed by default [ 0.530091] vgaarb: loaded [ 0.531266] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.533015] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.539016] clocksource: Switched to clocksource kvm-clock [ 0.645283] VFS: Disk quotas dquot_6.6.0 [ 0.646712] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.649410] *** VALIDATE ramfs *** [ 0.650780] *** VALIDATE hugetlbfs *** [ 0.652363] pnp: PnP ACPI init [ 0.655441] pnp: PnP ACPI: found 6 devices [ 0.685922] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.688987] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.690997] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.692708] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.694660] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.696826] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.699284] NET: Registered protocol family 2 [ 0.701248] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.705498] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.708201] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.712746] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.716215] TCP: Hash tables configured (established 65536 bind 65536) [ 0.718896] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.721978] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.724683] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.727592] NET: Registered protocol family 1 [ 0.730162] RPC: Registered named UNIX socket transport module. [ 0.732371] RPC: Registered udp transport module. [ 0.734150] RPC: Registered tcp transport module. [ 0.736142] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.739192] NET: Registered protocol family 44 [ 0.740927] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.743396] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.745342] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.747083] PCI: CLS 0 bytes, default 64 [ 0.748180] Unpacking initramfs... [ 2.155134] debug: unmapping init [mem 0xffff9fc8fcc64000-0xffff9fc8fffcffff] [ 2.158803] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.160686] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.163074] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.700753] Initialise system trusted keyrings [ 2.701954] Key type blacklist registered [ 2.703210] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.710127] zbud: loaded [ 2.712566] *** VALIDATE nfs *** [ 2.713778] *** VALIDATE nfs4 *** [ 2.715340] pstore: using deflate compression [ 2.719179] Platform Keyring initialized [ 2.806303] NET: Registered protocol family 38 [ 2.808158] Key type asymmetric registered [ 2.809792] Asymmetric key parser 'x509' registered [ 2.811688] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.814396] io scheduler mq-deadline registered [ 2.816034] io scheduler kyber registered [ 2.817312] io scheduler bfq registered [ 2.820029] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.822294] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.824292] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.826444] ACPI: Power Button [PWRF] [ 2.831721] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.836686] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.845975] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.870684] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.900062] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.905881] Non-volatile memory driver v1.3 [ 2.907655] Linux agpgart interface v0.103 [ 2.940662] virtio_blk virtio1: [vda] 146016 512-byte logical blocks (74.8 MB/71.3 MiB) [ 2.943695] vda: detected capacity change from 0 to 74760192 [ 2.967264] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.970209] vdb: detected capacity change from 0 to 1073741824 [ 2.976253] libphy: Fixed MDIO Bus: probed [ 2.992981] usbcore: registered new interface driver usbserial_generic [ 2.995300] usbserial: USB Serial support registered for generic [ 2.997984] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.001548] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.003639] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.006231] mousedev: PS/2 mouse device common for all mice [ 3.008776] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.010825] rtc_cmos 00:05: RTC can wake from S4 [ 3.014738] rtc_cmos 00:05: registered as rtc0 [ 3.016258] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.018640] intel_pstate: CPU model not supported [ 3.019567] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.023515] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.023566] hid: raw HID events driver (C) Jiri Kosina [ 3.027333] usbcore: registered new interface driver usbhid [ 3.028456] usbhid: USB HID core driver [ 3.029524] drop_monitor: Initializing network drop monitor service [ 3.031506] Initializing XFRM netlink socket [ 3.032723] NET: Registered protocol family 10 [ 3.035134] Segment Routing with IPv6 [ 3.035896] NET: Registered protocol family 17 [ 3.037093] mpls_gso: MPLS GSO support [ 3.041117] RAS: Correctable Errors collector initialized. [ 3.042247] AVX version of gcm_enc/dec engaged. [ 3.043224] AES CTR mode by8 optimization enabled [ 3.109857] sched_clock: Marking stable (3109837376, 0)->(3978191432, -868354056) [ 3.112334] registered taskstats version 1 [ 3.114270] Loading compiled-in X.509 certificates [ 3.115573] zswap: loaded using pool lzo/zbud [ 3.136486] Key type big_key registered [ 3.145610] Key type encrypted registered [ 3.146678] ima: No TPM chip found, activating TPM-bypass! [ 3.147988] ima: Allocated hash algorithm: sha1 [ 3.149222] ima: No architecture policies found [ 3.150352] evm: Initialising EVM extended attributes: [ 3.152497] evm: security.selinux [ 3.153698] evm: security.ima [ 3.155030] evm: security.capability [ 3.156312] evm: HMAC attrs: 0x1 [ 3.158438] rtc_cmos 00:05: setting system clock to 2026-08-06 16:46:34 UTC (1786034794) [ 3.163718] debug: unmapping init [mem 0xffffffffbb003000-0xffffffffbb1fffff] [ 3.166708] debug: unmapping init [mem 0xffffffffb9d82000-0xffffffffba058fff] [ 3.175936] Write protecting the kernel read-only data: 28672k [ 3.178023] debug: unmapping init [mem 0xffffffffb8403000-0xffffffffb85fffff] [ 3.179727] debug: unmapping init [mem 0xffffffffb8d14000-0xffffffffb8dfffff] [ 3.203051] 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.209464] systemd[1]: Detected virtualization kvm. [ 3.211432] systemd[1]: Detected architecture x86-64. [ 3.212697] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.239492] systemd[1]: No hostname configured. [ 3.241667] systemd[1]: Set hostname to . [ 3.243968] random: systemd: uninitialized urandom read (16 bytes read) [ 3.246944] systemd[1]: Initializing machine ID from random generator. [ 3.285732] random: ln: uninitialized urandom read (6 bytes read) [ 3.371481] random: systemd: uninitialized urandom read (16 bytes read) [ 3.374329] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.379128] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.387555] systemd[1]: Starting Journal Service... Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Swap. [ OK ] Reached target Slices. [ OK ] Reached target Local File Systems. Starting Setup Virtual Console... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Local Encrypted Volumes. Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Volatile Files and Directories.[ 3.517586] random: fast init done [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.954031] device-mapper: uevent: version 1.0.3 [ 3.955808] 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. [ 4.747592] virtio_net virtio0 ens2: renamed from eth0 [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.820445] scsi host0: ata_piix [ 4.857508] scsi host1: ata_piix [ 4.859667] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.862224] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.374326] dracut-initqueue[601]: RTNETLINK answers: File exists [ 9.660543] random: crng init done [ 9.661954] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.144827] 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. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.282191] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.535576] SELinux: Disabled at runtime. [ 11.597523] 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.605168] systemd[1]: Detected virtualization kvm. [ 11.607195] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.032919] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.036554] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.042755] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.048256] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.052109] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.060402] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.067561] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... [ OK ] Listening on Process Core Dump Socket. Starting Remount Root and Kernel File Systems... [ OK ] Reached target rpc_pipefs.target. Starting Apply Kernel Variables... Mounting POSIX Message Queue File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Kernel Socket. Mounting Huge Pages File System... [ 12.162800] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Stopped target Switch Root. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target RPC Port Mapper. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Flush Journal to Persistent Storage. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 12.427121] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 12.754870] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.772546] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.859700] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.878165] EDAC sbridge: Ver: 1.1.2 [ 14.861310] Key type dns_resolver registered [ 15.611462] NFS: Registering the id_resolver key type [ 15.620201] Key type id_resolver registered [ 15.626181] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... 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 Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning 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 Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting 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. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg130-client login: [ 88.055810] hrtimer: interrupt took 2818261 ns [ 93.702443] libcfs: loading out-of-tree module taints kernel. [ 94.329518] Key type ._llcrypt registered [ 94.331013] Key type .llcrypt registered [ 94.976158] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 94.996476] alg: No test for adler32 (adler32-zlib) [ 96.579411] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 97.755636] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 99.559175] Key type lgssc registered [ 101.542833] Lustre: Echo OBD driver; http://www.lustre.org/ [ 291.952723] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 298.169760] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 311.228522] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing check_logdir /tmp/testlogs/ [ 316.384101] Lustre: lustre-OST0000-osc-ffff9fc950cd1800: disconnect after 21s idle [ 316.564276] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing yml_node [ 321.928822] Lustre: DEBUG MARKER: Client: 2.17.56.52 [ 324.829862] Lustre: DEBUG MARKER: MDS: 2.17.56.52 [ 327.543548] Lustre: DEBUG MARKER: OSS: 2.17.56.52 [ 329.255208] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 6 12:51:59 EDT 2026 [ 347.782542] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 349.490687] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 352.478987] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 362.849116] Lustre: Unmounted lustre-client [ 485.204894] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 490.858822] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 513.770518] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 551.132031] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 12:55:40 (1786035340) [ 555.107232] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 558.501111] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 12:55:47 (1786035347) [ 598.698563] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 606.116443] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 629.819575] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 641.101332] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 646.678989] Lustre: Unmounted lustre-client [ 677.898907] Key type lgssc unregistered [ 678.205768] LNet: 15046:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 678.213821] LNetError: 15046:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 679.286423] LNet: Removed LNI 192.168.201.30@tcp [ 680.226195] Key type .llcrypt unregistered [ 680.228092] Key type ._llcrypt unregistered [ 697.269613] Key type ._llcrypt registered [ 697.274164] Key type .llcrypt registered [ 699.069341] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 12:58:08 (1786035488) [ 701.371633] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 701.452740] alg: No test for adler32 (adler32-zlib) [ 702.582947] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 702.942548] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 704.761910] Key type lgssc registered [ 706.440303] Lustre: Echo OBD driver; http://www.lustre.org/ [ 785.944971] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 870.704204] Lustre: Unmounted lustre-client [ 909.198941] Key type lgssc unregistered [ 909.550056] LNet: 18849:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 909.568372] LNetError: 18849:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 909.590642] LNet: Removed LNI 192.168.201.30@tcp [ 910.573680] Key type .llcrypt unregistered [ 910.575834] Key type ._llcrypt unregistered [ 920.955307] Key type ._llcrypt registered [ 920.965144] Key type .llcrypt registered [ 922.517133] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 13:01:52 (1786035712) [ 925.312375] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 925.477907] alg: No test for adler32 (adler32-zlib) [ 926.605979] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 926.898416] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 928.615434] Key type lgssc registered [ 929.998178] Lustre: Echo OBD driver; http://www.lustre.org/ [ 985.075609] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 991.368044] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1016.647252] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1018.781562] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 1031.307720] Lustre: Unmounted lustre-client [ 1061.506536] Key type lgssc unregistered [ 1061.802401] LNet: 22455:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1061.813447] LNetError: 22455:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1061.841282] LNet: Removed LNI 192.168.201.30@tcp [ 1062.627917] Key type .llcrypt unregistered [ 1062.634659] Key type ._llcrypt unregistered [ 1074.941024] Key type ._llcrypt registered [ 1074.953632] Key type .llcrypt registered [ 1076.612910] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 13:04:26 (1786035866) [ 1079.209421] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1079.400730] alg: No test for adler32 (adler32-zlib) [ 1080.464402] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 1080.822197] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 1082.559518] Key type lgssc registered [ 1083.704573] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1136.254592] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1143.529939] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1168.839694] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1170.708669] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 1178.005187] Lustre: Unmounted lustre-client [ 1218.070740] Key type lgssc unregistered [ 1218.637749] LNet: 25650:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1218.643379] LNetError: 25650:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1218.652806] LNet: Removed LNI 192.168.201.30@tcp [ 1219.312762] Key type .llcrypt unregistered [ 1219.314945] Key type ._llcrypt unregistered [ 1235.358577] Key type ._llcrypt registered [ 1235.360941] Key type .llcrypt registered [ 1236.815983] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 13:07:06 (1786036026) [ 1239.565818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1239.574851] alg: No test for adler32 (adler32-zlib) [ 1240.649790] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 1240.862459] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 1242.527219] Key type lgssc registered [ 1243.549606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1294.305857] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1300.148316] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1321.520473] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1323.289443] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 1333.741907] Lustre: lustre-MDT0000-mdc-ffff9fc950cd5800: Connection to lustre-MDT0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1343.980382] Lustre: lustre-MDT0001-mdc-ffff9fc950cd5800: Connection to lustre-MDT0001 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1350.336091] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1355.237454] Lustre: 26609:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786036130/real 1786036130] req@0000000070d36b12 x1872794518296576/t0(0) o400->MGC192.168.201.130@tcp@192.168.201.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1786036146 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1355.255205] LustreError: MGC192.168.201.130@tcp: Connection to MGS (at 192.168.201.130@tcp) was lost; in progress operations using this service will fail [ 1360.723634] Lustre: Unmounted lustre-client [ 1374.668220] Key type lgssc unregistered [ 1374.977232] LNet: 29306:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1374.986393] LNetError: 29306:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1375.009302] LNet: Removed LNI 192.168.201.30@tcp [ 1375.692807] Key type .llcrypt unregistered [ 1375.695228] Key type ._llcrypt unregistered [ 1392.683761] Key type ._llcrypt registered [ 1392.689240] Key type .llcrypt registered [ 1394.713158] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 13:09:43 (1786036183) [ 1398.050970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1398.250777] alg: No test for adler32 (adler32-zlib) [ 1399.584970] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 1399.932897] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 1401.599881] Key type lgssc registered [ 1403.303166] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1457.819272] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1464.105189] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1485.403816] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1487.310036] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 1492.478275] Lustre: lustre-OST0000-osc-ffff9fc952583000: Connection to lustre-OST0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1508.435934] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1518.768742] Lustre: Unmounted lustre-client [ 1547.391573] Key type lgssc unregistered [ 1547.691785] LNet: 32920:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1547.703240] LNetError: 32920:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1548.772852] LNet: Removed LNI 192.168.201.30@tcp [ 1549.733084] Key type .llcrypt unregistered [ 1549.735320] Key type ._llcrypt unregistered [ 1567.326510] Key type ._llcrypt registered [ 1567.332262] Key type .llcrypt registered [ 1568.905794] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 13:12:38 (1786036358) [ 1572.245326] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1572.389682] alg: No test for adler32 (adler32-zlib) [ 1573.725156] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 1574.097977] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 1575.903604] Key type lgssc registered [ 1577.350243] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1635.616783] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1642.172681] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1664.323392] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1675.363741] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 1678.799215] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1683.527876] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1695.971765] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-0613h14m44s [ 1698.553065] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1705.966263] Lustre: lustre-MDT0000-mdc-ffff9fc9526c7800: Connection to lustre-MDT0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1709.833294] LustreError: 36709:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9fc9526c7800: can't stat MDS #0: rc = -19 [ 1711.758562] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1719.283886] LustreError: MGC192.168.201.130@tcp: Connection to MGS (at 192.168.201.130@tcp) was lost; in progress operations using this service will fail [ 1719.304507] Lustre: Evicted from MGS (at 192.168.201.130@tcp) after server handle changed from 0x8b71ead9922795ea to 0x8b71ead9922797d4 [ 1719.321927] Lustre: MGC192.168.201.130@tcp: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [ 1725.427430] LustreError: lustre-MDT0000-mdc-ffff9fc9526c7800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1725.463646] Lustre: lustre-MDT0000-mdc-ffff9fc9526c7800: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [ 1729.079518] Lustre: DEBUG MARKER: Wait for df (36709) ... [ 1731.228168] Lustre: DEBUG MARKER: done [ 1734.498123] Lustre: Unmounted lustre-client [ 1774.307884] Key type lgssc unregistered [ 1774.655370] LNet: 37728:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1774.669472] LNetError: 37728:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1774.710742] LNet: Removed LNI 192.168.201.30@tcp [ 1775.593746] Key type .llcrypt unregistered [ 1775.601715] Key type ._llcrypt unregistered [ 1809.779259] Key type ._llcrypt registered [ 1809.781972] Key type .llcrypt registered [ 1811.416260] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 13:16:40 (1786036600) [ 1814.592549] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1814.657065] alg: No test for adler32 (adler32-zlib) [ 1815.962824] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 1816.279542] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 1818.015172] Key type lgssc registered [ 1819.586556] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1874.848462] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1882.248269] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1905.635866] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1908.521850] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 1914.221463] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-0613h18m23s [ 1916.618650] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1923.739600] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1926.667786] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1947.103222] Lustre: 41877:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786036718/real 1786036718] req@00000000fa991285 x1872795121228416/t0(0) o101->lustre-MDT0000-mdc-ffff9fc9606eb000@192.168.201.130@tcp:12/10 lens 576/1152 e 0 to 1 dl 1786036738 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1947.126936] Lustre: lustre-MDT0000-mdc-ffff9fc9606eb000: Connection to lustre-MDT0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1947.167672] Lustre: lustre-MDT0000-mdc-ffff9fc9606eb000: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [ 1968.722836] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1972.145560] Lustre: DEBUG MARKER: done [ 1975.649763] Lustre: Unmounted lustre-client [ 2011.525993] Key type lgssc unregistered [ 2011.926556] LNet: 42778:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2011.931783] LNetError: 42778:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2011.945309] LNet: Removed LNI 192.168.201.30@tcp [ 2012.808874] Key type .llcrypt unregistered [ 2012.811313] Key type ._llcrypt unregistered [ 2048.688848] Key type ._llcrypt registered [ 2048.692322] Key type .llcrypt registered [ 2050.491612] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 13:20:39 (1786036839) [ 2052.148902] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2054.639668] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 13:20:43 (1786036843) [ 2058.756559] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2058.775521] alg: No test for adler32 (adler32-zlib) [ 2059.947388] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 2060.322250] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 2062.055365] Key type lgssc registered [ 2063.927353] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2093.693802] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 13:21:23 (1786036883) [ 2131.481213] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2138.434317] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2162.769990] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2165.582279] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 2169.306758] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2173.979040] Lustre: Unmounted lustre-client [ 2193.120488] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2242.004618] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2248.282317] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2249.091236] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 2253.987627] Lustre: Unmounted lustre-client [ 2320.399227] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2326.942483] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2327.500458] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 2332.187532] Lustre: Unmounted lustre-client [ 2356.274440] Lustre: DEBUG MARKER: files compared the same [ 2381.417642] Key type lgssc unregistered [ 2381.805733] LNet: 50412:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2381.823688] LNetError: 50412:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2381.842686] LNet: Removed LNI 192.168.201.30@tcp [ 2382.671235] Key type .llcrypt unregistered [ 2382.676493] Key type ._llcrypt unregistered [ 2399.244950] Key type ._llcrypt registered [ 2399.251064] Key type .llcrypt registered [ 2400.861149] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 13:26:30 (1786037190) [ 2403.371158] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2403.563153] alg: No test for adler32 (adler32-zlib) [ 2404.738161] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 2405.209046] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 2406.943240] Key type lgssc registered [ 2408.894574] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2463.869789] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2469.479932] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2493.782906] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2496.480066] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 2501.184867] Lustre: Unmounted lustre-client [ 2532.438437] Key type lgssc unregistered [ 2532.741391] LNet: 54003:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2532.750265] LNetError: 54003:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2532.784663] LNet: Removed LNI 192.168.201.30@tcp [ 2534.054695] Key type .llcrypt unregistered [ 2534.057673] Key type ._llcrypt unregistered [ 2550.870780] Key type ._llcrypt registered [ 2550.876433] Key type .llcrypt registered [ 2552.985633] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 13:29:02 (1786037342) [ 2555.543942] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2555.558574] alg: No test for adler32 (adler32-zlib) [ 2556.755689] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 2557.108517] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 2558.871297] Key type lgssc registered [ 2560.477758] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2654.479916] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2692.062584] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2698.670535] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2731.141980] Key type lgssc unregistered [ 2731.690862] LNet: 57497:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2731.717561] LNetError: 57497:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2731.764911] LNet: Removed LNI 192.168.201.30@tcp [ 2732.771972] Key type .llcrypt unregistered [ 2732.775318] Key type ._llcrypt unregistered [ 2753.634672] Key type ._llcrypt registered [ 2753.637867] Key type .llcrypt registered [ 2755.006869] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 13:32:24 (1786037544) [ 2758.960756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2759.064034] alg: No test for adler32 (adler32-zlib) [ 2760.142678] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 2760.401354] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 2762.119286] Key type lgssc registered [ 2763.402771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2838.677966] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 2846.669222] Lustre: Unmounted lustre-client [ 2886.607872] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 13:34:36 (1786037676) [ 3044.063599] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3062.775816] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 3071.379714] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3071.384329] LustreError: 63980:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9fc952525800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3071.399488] Lustre: 58483:0:(llite_lib.c:4341:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.130@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3081.797062] Lustre: Unmounted lustre-client [ 3130.342466] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 13:38:40 (1786037920) [ 3143.978220] Key type lgssc unregistered [ 3144.274762] LNet: 65196:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3144.290378] LNetError: 65196:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3144.311123] LNet: Removed LNI 192.168.201.30@tcp [ 3145.020114] Key type .llcrypt unregistered [ 3145.021482] Key type ._llcrypt unregistered [ 3158.244481] Key type ._llcrypt registered [ 3158.246804] Key type .llcrypt registered [ 3158.695700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3158.709602] alg: No test for adler32 (adler32-zlib) [ 3159.875889] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 3160.301391] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 3162.055274] Key type lgssc registered [ 3163.284789] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3217.123497] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3224.447493] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3256.380309] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3258.471913] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 3265.931933] Lustre: Unmounted lustre-client [ 3297.224374] Key type lgssc unregistered [ 3297.561128] LNet: 68210:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3297.577083] LNetError: 68210:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3297.599924] LNet: Removed LNI 192.168.201.30@tcp [ 3298.596805] Key type .llcrypt unregistered [ 3298.601653] Key type ._llcrypt unregistered [ 3314.659515] Key type ._llcrypt registered [ 3314.813191] Key type .llcrypt registered [ 3315.253624] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3315.293321] alg: No test for adler32 (adler32-zlib) [ 3316.436273] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 3316.787096] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 3318.551509] Key type lgssc registered [ 3320.073976] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3450.135663] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3457.952169] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3485.471320] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3492.423927] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 3495.549537] Lustre: Unmounted lustre-client [ 3534.232079] Key type lgssc unregistered [ 3534.513808] LNet: 71653:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3534.520554] LNetError: 71653:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3534.530918] LNet: Removed LNI 192.168.201.30@tcp [ 3535.276216] Key type .llcrypt unregistered [ 3535.283517] Key type ._llcrypt unregistered [ 3553.537739] Key type ._llcrypt registered [ 3553.540609] Key type .llcrypt registered [ 3555.420992] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 13:45:44 (1786038344) [ 3559.219179] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3559.406798] alg: No test for adler32 (adler32-zlib) [ 3560.624294] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 3561.105035] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 3562.959198] Key type lgssc registered [ 3564.178064] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3624.237318] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3632.123533] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3657.748579] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3664.954173] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 3673.195206] Lustre: Unmounted lustre-client [ 3673.816440] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 3678.210711] Lustre: Unmounted lustre-client [ 3709.991164] Key type lgssc unregistered [ 3710.364924] LNet: 75343:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3710.380526] LNetError: 75343:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3710.410647] LNet: Removed LNI 192.168.201.30@tcp [ 3711.668216] Key type .llcrypt unregistered [ 3711.669765] Key type ._llcrypt unregistered [ 3728.252062] Key type ._llcrypt registered [ 3728.253836] Key type .llcrypt registered [ 3729.106831] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3729.126622] alg: No test for adler32 (adler32-zlib) [ 3730.397059] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 3730.933448] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 3732.727261] Key type lgssc registered [ 3735.185819] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3808.021752] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3816.418507] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3841.911493] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3843.785148] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 3849.167845] Lustre: Unmounted lustre-client [ 3888.624680] Key type lgssc unregistered [ 3888.987081] LNet: 78385:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3889.008473] LNetError: 78385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3889.067562] LNet: Removed LNI 192.168.201.30@tcp [ 3890.226974] Key type .llcrypt unregistered [ 3890.231916] Key type ._llcrypt unregistered [ 3908.908407] Key type ._llcrypt registered [ 3908.911914] Key type .llcrypt registered [ 3910.522470] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 13:51:40 (1786038700) [ 3914.224534] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3914.382466] alg: No test for adler32 (adler32-zlib) [ 3915.671955] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 3916.134686] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 3917.936132] Key type lgssc registered [ 3919.270529] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3978.085666] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3985.378587] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4010.101286] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4017.303790] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 4028.752230] Lustre: lustre: root_squash is set to 0:0 [ 4036.198811] Lustre: lustre: nosquash_nids is cleared [ 4036.267249] Lustre: lustre: root_squash is set to 500:500 [ 4052.507646] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.30@tcp 192.168.0.[2,10]@tcp [ 4065.340394] Lustre: lustre: nosquash_nids set to 192.168.201.30/24@tcp [ 4071.906773] Lustre: Unmounted lustre-client [ 4104.796048] Key type lgssc unregistered [ 4105.070447] LNet: 82357:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4105.083165] LNetError: 82357:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4105.103625] LNet: Removed LNI 192.168.201.30@tcp [ 4105.828662] Key type .llcrypt unregistered [ 4105.830971] Key type ._llcrypt unregistered [ 4126.231929] Key type ._llcrypt registered [ 4126.233611] Key type .llcrypt registered [ 4128.351481] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 13:55:17 (1786038917) [ 4131.410734] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 4133.913163] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 13:55:23 (1786038923) [ 4137.129328] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4137.147758] alg: No test for adler32 (adler32-zlib) [ 4138.282395] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 4138.617879] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 4140.351275] Key type lgssc registered [ 4141.894773] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4199.077176] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4206.029845] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4229.260974] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4229.798282] Lustre: lustre: root_squash is set to 500:500 [ 4229.824897] Lustre: lustre: nosquash_nids set to 192.168.201.30/24@tcp [ 4230.945873] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 4238.035366] Lustre: Unmounted lustre-client [ 4281.770636] Key type lgssc unregistered [ 4282.124373] LNet: 86320:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4282.131089] LNetError: 86320:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4282.168572] LNet: Removed LNI 192.168.201.30@tcp [ 4283.148813] Key type .llcrypt unregistered [ 4283.154163] Key type ._llcrypt unregistered [ 4305.917810] Key type ._llcrypt registered [ 4305.920648] Key type .llcrypt registered [ 4307.514880] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4309.802983] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 13:58:19 (1786039099) [ 4331.732418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4331.914065] alg: No test for adler32 (adler32-zlib) [ 4333.188153] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 4333.615854] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 4335.440559] Key type lgssc registered [ 4336.995065] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4431.567940] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4439.239751] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4464.959924] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4539.742803] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4546.403796] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4568.061845] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4585.237303] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 4620.499962] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9fc9529eb800.ost_server_uuid 50 [ 4622.352224] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9fc9529eb800.ost_server_uuid in IDLE state after 0 sec [ 4622.812099] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 4627.252849] Lustre: Unmounted lustre-client [ 4629.655730] Lustre: Unmounted lustre-client [ 4683.086967] Key type lgssc unregistered [ 4683.432755] LNet: 94002:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4683.439890] LNetError: 94002:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4683.458404] LNet: Removed LNI 192.168.201.30@tcp [ 4684.507252] Key type .llcrypt unregistered [ 4684.509683] Key type ._llcrypt unregistered [ 4718.620964] Key type ._llcrypt registered [ 4718.622656] Key type .llcrypt registered [ 4720.280755] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 14:05:09 (1786039509) [ 4742.906540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4742.930582] alg: No test for adler32 (adler32-zlib) [ 4744.068918] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 4744.299611] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 4745.951252] Key type lgssc registered [ 4747.049181] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4828.880037] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4835.716615] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4862.992656] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4865.868526] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 4887.391180] Lustre: 95367:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786039662/real 1786039662] req@0000000060f79428 x1872798191457536/t0(0) o400->lustre-OST0000-osc-ffff9fc9524e8800@192.168.201.130@tcp:28/4 lens 224/224 e 0 to 1 dl 1786039678 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4887.416526] Lustre: lustre-OST0000-osc-ffff9fc9524e8800: Connection to lustre-OST0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4892.575656] Lustre: 95367:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786039667/real 1786039667] req@0000000036bae15e x1872798191458048/t0(0) o400->lustre-OST0000-osc-ffff9fc9524e8800@192.168.201.130@tcp:28/4 lens 224/224 e 0 to 1 dl 1786039683 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4896.614145] Lustre: lustre-OST0000-osc-ffff9fc9524e8800: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [ 4896.727332] Lustre: 95366:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786039672/real 1786039672] req@0000000044e28914 x1872798191458560/t0(0) o400->lustre-OST0000-osc-ffff9fc9524e8800@192.168.201.130@tcp:28/4 lens 224/224 e 0 to 1 dl 1786039688 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4901.855288] Lustre: 95366:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786039677/real 1786039677] req@00000000dc470a76 x1872798191459072/t0(0) o400->lustre-OST0000-osc-ffff9fc9524e8800@192.168.201.130@tcp:28/4 lens 224/224 e 0 to 1 dl 1786039693 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4902.905529] Lustre: lustre-MDT0000-mdc-ffff9fc9524e8800: Connection to lustre-MDT0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4919.263146] Lustre: 95367:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786039694/real 1786039694] req@000000003c0a0dae x1872798191461248/t0(0) o400->MGC192.168.201.130@tcp@192.168.201.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1786039710 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4919.302724] LustreError: MGC192.168.201.130@tcp: Connection to MGS (at 192.168.201.130@tcp) was lost; in progress operations using this service will fail [ 4929.535675] Lustre: Evicted from MGS (at 192.168.201.130@tcp) after server handle changed from 0x46652433a424f8b6 to 0x46652433a424fb48 [ 4929.555538] Lustre: MGC192.168.201.130@tcp: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [ 4933.976236] Lustre: lustre-MDT0000-mdc-ffff9fc9524e8800: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [ 4940.309775] Lustre: Unmounted lustre-client [ 4967.744892] Key type lgssc unregistered [ 4968.053760] LNet: 99673:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4968.060621] LNetError: 99673:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4968.098891] LNet: Removed LNI 192.168.201.30@tcp [ 4969.327370] Key type .llcrypt unregistered [ 4969.332164] Key type ._llcrypt unregistered [ 4986.950975] Key type ._llcrypt registered [ 4986.956132] Key type .llcrypt registered [ 4988.437491] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 14:09:38 (1786039778) [ 5004.680185] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5004.698582] alg: No test for adler32 (adler32-zlib) [ 5005.807703] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 5006.011788] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 5007.680528] Key type lgssc registered [ 5009.251468] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5053.653403] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5160.780073] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5167.444903] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5193.630467] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5195.481978] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 5197.880988] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5200.052472] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 14:13:09 (1786039989) [ 5445.062479] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5455.454730] Lustre: Unmounted lustre-client [ 5590.912748] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5598.073234] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5623.811667] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5666.368717] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:20:56 (1786040456) [ 5765.045323] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5771.503818] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5795.715758] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5796.624902] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 5800.334114] Lustre: Unmounted lustre-client [ 5833.061531] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:23:43 (1786040623) [ 5928.603457] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5934.633499] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5958.873273] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5960.130541] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 5965.254438] Lustre: Unmounted lustre-client [ 6000.438985] Key type lgssc unregistered [ 6000.717510] LNet: 122697:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6000.724792] LNetError: 122697:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6001.767713] LNet: Removed LNI 192.168.201.30@tcp [ 6002.264689] Key type .llcrypt unregistered [ 6002.266682] Key type ._llcrypt unregistered [ 6018.308583] Key type ._llcrypt registered [ 6018.309883] Key type .llcrypt registered [ 6019.433089] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 14:26:49 (1786040809) [ 6022.067527] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6022.135679] alg: No test for adler32 (adler32-zlib) [ 6023.414277] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 6023.875566] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 6025.583246] Key type lgssc registered [ 6026.831462] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6080.914126] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6087.559437] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6110.464388] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6112.118733] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 6126.117294] Lustre: Unmounted lustre-client [ 6155.953905] Key type lgssc unregistered [ 6156.237960] LNet: 126293:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6156.246062] LNetError: 126293:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6156.260878] LNet: Removed LNI 192.168.201.30@tcp [ 6157.077637] Key type .llcrypt unregistered [ 6157.082173] Key type ._llcrypt unregistered [ 6174.833636] Key type ._llcrypt registered [ 6174.837777] Key type .llcrypt registered [ 6176.854178] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 14:29:25 (1786040965) [ 6179.300447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6179.411408] alg: No test for adler32 (adler32-zlib) [ 6180.800825] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 6181.194695] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 6182.951252] Key type lgssc registered [ 6184.552943] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6239.216232] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6244.432506] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6264.961602] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6267.723216] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 6273.029073] Lustre: lustre-OST0000-osc-ffff9fc9606eb000: Connection to lustre-OST0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6285.134246] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9fc9606eb000.ost_server_uuid 50 [ 6286.618435] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6298.375266] Lustre: Unmounted lustre-client [ 6319.529896] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 14:31:49 (1786041109) [ 6353.056643] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6358.698972] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6381.394991] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6410.677961] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6411.783374] LustreError: 127251:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 6411.886085] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 6416.916876] Lustre: lustre-OST0000-osc-ffff9fc94698f800: Connection to lustre-OST0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6443.457137] Lustre: Unmounted lustre-client [ 6492.771300] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 14:34:42 (1786041282) [ 6526.688845] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6532.799469] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6553.381957] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6578.762935] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6579.383948] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 6584.812506] Lustre: lustre-OST0000-osc-ffff9fc9477bc000: Connection to lustre-OST0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6601.185916] Lustre: Unmounted lustre-client [ 6647.269679] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 14:37:16 (1786041436) [ 6747.126906] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6753.914294] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6780.243394] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6859.025979] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6866.512834] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6889.634744] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6919.740880] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 6962.857782] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6966.329894] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 2 sec [ 6968.641589] Lustre: Unmounted lustre-client [ 7007.298741] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 14:43:17 (1786041797) [ 7040.519833] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7048.007420] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7070.486850] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7100.173923] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7138.153726] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 7181.817922] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7183.691509] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7204.729735] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7215.124809] Lustre: Unmounted lustre-client [ 7266.595651] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 14:47:36 (1786042056) [ 7304.444664] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7310.515558] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7332.219985] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7333.239692] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 7356.526253] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7358.049164] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7373.088228] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9fc9606ef800.ost_server_uuid 50 [ 7374.493273] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9fc9606ef800.ost_server_uuid in IDLE state after 0 sec [ 7377.460630] Lustre: Unmounted lustre-client [ 7377.766849] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7377.773447] Lustre: Skipped 3 previous similar messages [ 7377.853380] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 7378.627138] LustreError: 149737:0:(sec.c:403:import_sec_validate_get()) import 00000000efba363f (NEW) with no sec [ 7444.335266] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 14:50:33 (1786042233) [ 7479.499467] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7485.263165] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7505.746935] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7527.549731] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7528.017945] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7528.178660] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 7541.936383] Lustre: Unmounted lustre-client [ 7541.938485] Lustre: Skipped 1 previous similar message [ 7579.400603] Key type lgssc unregistered [ 7579.809204] LNet: 153153:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7579.817701] LNetError: 153153:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7579.836437] LNet: Removed LNI 192.168.201.30@tcp [ 7580.904716] Key type .llcrypt unregistered [ 7580.906436] Key type ._llcrypt unregistered [ 7599.232672] Key type ._llcrypt registered [ 7599.237226] Key type .llcrypt registered [ 7601.503769] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 14:53:10 (1786042390) [ 7603.483845] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7603.572750] alg: No test for adler32 (adler32-zlib) [ 7604.549491] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 7604.894216] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 7606.671217] Key type lgssc registered [ 7608.457548] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7670.179613] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7677.356464] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7704.890181] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7724.714975] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7726.366352] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7727.576322] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 7735.342648] LustreError: lustre-MDT0001-mdc-ffff9fc9529ed800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7735.371172] Lustre: lustre-MDT0001-mdc-ffff9fc9529ed800: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [ 7741.533619] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7743.374221] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7764.481898] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7767.271088] Lustre: Unmounted lustre-client [ 7797.146702] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 14:56:27 (1786042587) [ 7894.044186] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7900.231915] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7924.345396] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7925.042603] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 7952.979372] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7955.064959] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7978.669197] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7989.072836] Lustre: Unmounted lustre-client [ 8023.557334] Key type lgssc unregistered [ 8023.873143] LNet: 162774:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8023.881869] LNetError: 162774:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8024.944552] LNet: Removed LNI 192.168.201.30@tcp [ 8025.772263] Key type .llcrypt unregistered [ 8025.774080] Key type ._llcrypt unregistered [ 8059.535155] Key type ._llcrypt registered [ 8059.537878] Key type .llcrypt registered [ 8061.075183] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 15:00:50 (1786042850) [ 8063.711025] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8063.741514] alg: No test for adler32 (adler32-zlib) [ 8064.821492] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 8065.019516] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 8066.687226] Key type lgssc registered [ 8067.878435] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8125.230653] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8132.543398] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8154.795968] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8157.594489] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 8204.400442] Lustre: Unmounted lustre-client [ 8248.465980] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8249.242475] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 8286.546967] Lustre: Unmounted lustre-client [ 8317.315515] Key type lgssc unregistered [ 8317.590936] LNet: 167242:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8317.620205] LNetError: 167242:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8317.642599] LNet: Removed LNI 192.168.201.30@tcp [ 8318.747308] Key type .llcrypt unregistered [ 8318.751661] Key type ._llcrypt unregistered [ 8335.228156] Key type ._llcrypt registered [ 8335.231658] Key type .llcrypt registered [ 8337.051284] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 15:05:26 (1786043126) [ 8339.988390] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8340.030560] alg: No test for adler32 (adler32-zlib) [ 8341.363380] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 8341.740317] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 8343.537938] Key type lgssc registered [ 8345.014826] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8397.756342] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8404.015170] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8427.895657] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8430.738169] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 8448.588652] Lustre: Unmounted lustre-client [ 8475.534833] Key type lgssc unregistered [ 8475.781397] LNet: 171004:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8475.786078] LNetError: 171004:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8476.847574] LNet: Removed LNI 192.168.201.30@tcp [ 8477.754196] Key type .llcrypt unregistered [ 8477.756233] Key type ._llcrypt unregistered [ 8491.635464] Key type ._llcrypt registered [ 8491.643498] Key type .llcrypt registered [ 8492.391831] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8492.420772] alg: No test for adler32 (adler32-zlib) [ 8493.785255] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 8494.329535] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 8496.087321] Key type lgssc registered [ 8497.375543] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8553.979950] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8560.454595] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8586.481080] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8588.558443] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 8598.320354] Lustre: Unmounted lustre-client [ 8638.567551] Key type lgssc unregistered [ 8638.842081] LNet: 174077:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8638.852405] LNetError: 174077:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8638.870031] LNet: Removed LNI 192.168.201.30@tcp [ 8639.694250] Key type .llcrypt unregistered [ 8639.698855] Key type ._llcrypt unregistered [ 8658.305814] Key type ._llcrypt registered [ 8658.308906] Key type .llcrypt registered [ 8660.298213] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 15:10:49 (1786043449) [ 8664.000072] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8664.013063] alg: No test for adler32 (adler32-zlib) [ 8665.243320] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 8665.589259] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 8667.283562] Key type lgssc registered [ 8668.876087] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8723.853783] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8730.120473] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8752.854058] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8755.991982] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 8780.883035] Lustre: Unmounted lustre-client [ 8810.823580] Key type lgssc unregistered [ 8811.178993] LNet: 177841:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8811.188645] LNetError: 177841:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8811.204333] LNet: Removed LNI 192.168.201.30@tcp [ 8812.033807] Key type .llcrypt unregistered [ 8812.046387] Key type ._llcrypt unregistered [ 8828.074783] Key type ._llcrypt registered [ 8828.080536] Key type .llcrypt registered [ 8828.810615] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8828.853499] alg: No test for adler32 (adler32-zlib) [ 8830.050835] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 8830.359933] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 8832.071493] Key type lgssc registered [ 8833.635992] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8887.664385] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8894.080339] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8922.279376] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8924.232578] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 8935.846826] Lustre: Unmounted lustre-client [ 8973.958969] Key type lgssc unregistered [ 8974.280638] LNet: 180916:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8974.286141] LNetError: 180916:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8974.309216] LNet: Removed LNI 192.168.201.30@tcp [ 8975.308110] Key type .llcrypt unregistered [ 8975.310165] Key type ._llcrypt unregistered [ 8993.890959] Key type ._llcrypt registered [ 8993.896485] Key type .llcrypt registered [ 8995.452989] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 15:16:24 (1786043784) [ 9019.653807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9019.701507] alg: No test for adler32 (adler32-zlib) [ 9020.853969] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 9021.087146] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 9022.775564] Key type lgssc registered [ 9024.644251] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9112.249499] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9118.422425] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9142.826518] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9187.388379] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 15:19:36 (1786043976) [ 9225.774801] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9233.517898] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9258.160164] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9270.371993] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 9276.442834] Lustre: Unmounted lustre-client [ 9301.724875] Key type lgssc unregistered [ 9302.050414] LNet: 188023:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9302.057197] LNetError: 188023:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9302.080814] LNet: Removed LNI 192.168.201.30@tcp [ 9303.005933] Key type .llcrypt unregistered [ 9303.008631] Key type ._llcrypt unregistered [ 9318.823595] Key type ._llcrypt registered [ 9318.825553] Key type .llcrypt registered [ 9320.586715] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9322.513859] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 15:21:52 (1786044112) [ 9343.032905] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9343.171887] alg: No test for adler32 (adler32-zlib) [ 9344.383189] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 9344.740914] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 9346.560381] Key type lgssc registered [ 9348.603233] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9454.893746] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9463.253975] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9495.938389] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9521.867334] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9523.970528] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [ 9554.316287] Lustre: Unmounted lustre-client [ 9687.438994] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9694.042281] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9716.704324] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9755.650027] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 15:29:05 (1786044545) [ 9757.094767] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9758.561671] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 15:29:08 (1786044548) [ 9852.099474] Key type lgssc unregistered [ 9852.416494] LNet: 199468:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9852.425828] LNetError: 199468:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9852.451508] LNet: Removed LNI 192.168.201.30@tcp [ 9853.477265] Key type .llcrypt unregistered [ 9853.481580] Key type ._llcrypt unregistered [ 9871.056292] Key type ._llcrypt registered [ 9871.058418] Key type .llcrypt registered [ 9872.725964] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 15:31:02 (1786044662) [ 9914.592297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9914.683764] alg: No test for adler32 (adler32-zlib) [ 9916.024548] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [ 9916.291861] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [ 9918.095149] Key type lgssc registered [ 9919.776293] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9970.683615] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9978.514198] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10017.763618] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10058.270648] Key type lgssc unregistered [10058.626352] LNet: 203221:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10058.632494] LNetError: 203221:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10058.660126] LNet: Removed LNI 192.168.201.30@tcp [10059.958927] Key type .llcrypt unregistered [10059.960510] Key type ._llcrypt unregistered [10076.447658] Key type ._llcrypt registered [10076.449491] Key type .llcrypt registered [10077.856190] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 15:34:27 (1786044867) [10080.499569] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10080.556158] alg: No test for adler32 (adler32-zlib) [10081.825207] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [10082.131506] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [10083.823536] Key type lgssc registered [10085.345427] Lustre: Echo OBD driver; http://www.lustre.org/ [10144.799790] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10152.362318] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10180.120090] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10186.318435] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [10196.469295] Lustre: lustre-MDT0000-mdc-ffff9fc9606ef000: Connection to lustre-MDT0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [10206.735220] Lustre: lustre-MDT0001-mdc-ffff9fc9606ef000: Connection to lustre-MDT0001 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [10216.927217] Lustre: 204208:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786044992/real 1786044992] req@00000000614363ad x1872803788832896/t0(0) o400->MGC192.168.201.130@tcp@192.168.201.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1786045008 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10216.943902] LustreError: MGC192.168.201.130@tcp: Connection to MGS (at 192.168.201.130@tcp) was lost; in progress operations using this service will fail [10242.533903] Lustre: Evicted from MGS (at 192.168.201.130@tcp) after server handle changed from 0xc5a7f2df92154be to 0xc5a7f2df9216f2f [10242.550972] Lustre: MGC192.168.201.130@tcp: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [10272.235172] LustreError: lustre-MDT0000-mdc-ffff9fc9606ef000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10272.285086] Lustre: lustre-MDT0000-mdc-ffff9fc9606ef000: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [10279.008256] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10281.240063] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10288.203365] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10290.281201] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10293.755697] Lustre: Unmounted lustre-client [10324.578493] Key type lgssc unregistered [10324.857649] LNet: 208172:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10324.863596] LNetError: 208172:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10324.876744] LNet: Removed LNI 192.168.201.30@tcp [10325.748882] Key type .llcrypt unregistered [10325.750768] Key type ._llcrypt unregistered [10346.797285] Key type ._llcrypt registered [10346.799568] Key type .llcrypt registered [10348.434430] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 15:38:57 (1786045137) [10352.195289] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10352.226075] alg: No test for adler32 (adler32-zlib) [10353.526528] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [10353.953070] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [10355.791276] Key type lgssc registered [10357.580540] Lustre: Echo OBD driver; http://www.lustre.org/ [10416.027891] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10423.580901] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10484.369382] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10490.212433] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10529.940818] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10555.221644] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10601.331734] Key type lgssc unregistered [10601.591841] LNet: 213472:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10601.603032] LNetError: 213472:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10601.624776] LNet: Removed LNI 192.168.201.30@tcp [10602.654788] Key type .llcrypt unregistered [10602.659165] Key type ._llcrypt unregistered [10656.233910] Key type ._llcrypt registered [10656.236341] Key type .llcrypt registered [10658.117836] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 15:44:07 (1786045447) [10702.359039] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10702.382234] alg: No test for adler32 (adler32-zlib) [10703.612595] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [10703.970178] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [10705.655432] Key type lgssc registered [10706.773724] Lustre: Echo OBD driver; http://www.lustre.org/ [10795.541176] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10801.784968] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10827.535666] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10873.131747] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 15:47:42 (1786045662) [10882.135439] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 15:47:51 (1786045671) [10917.717814] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10924.202542] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10949.466857] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10951.294952] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [10957.184700] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10959.391197] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10961.049608] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10963.496728] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10966.522746] Lustre: lustre-MDT0000-mdc-ffff9fc952581800: Connection to lustre-MDT0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [10981.855477] Lustre: 215368:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786045757/real 1786045757] req@00000000c3c98187 x1872804440974976/t0(0) o400->MGC192.168.201.130@tcp@192.168.201.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1786045773 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10981.898576] LustreError: MGC192.168.201.130@tcp: Connection to MGS (at 192.168.201.130@tcp) was lost; in progress operations using this service will fail [10992.129987] Lustre: Evicted from MGS (at 192.168.201.130@tcp) after server handle changed from 0x519c1282d9e23a0c to 0x519c1282d9e23b7f [10992.143886] Lustre: MGC192.168.201.130@tcp: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [11000.631177] Lustre: lustre-MDT0000-mdc-ffff9fc952581800: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [11012.552123] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11014.344019] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11018.358499] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [11022.853185] Lustre: lustre-MDT0000-mdc-ffff9fc952581800: Connection to lustre-MDT0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [11039.201941] Lustre: 215367:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786045814/real 1786045814] req@0000000019f68128 x1872804440984448/t0(0) o400->MGC192.168.201.130@tcp@192.168.201.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1786045830 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11039.237731] LustreError: MGC192.168.201.130@tcp: Connection to MGS (at 192.168.201.130@tcp) was lost; in progress operations using this service will fail [11049.501338] Lustre: Evicted from MGS (at 192.168.201.130@tcp) after server handle changed from 0x519c1282d9e23b7f to 0x519c1282d9e23f92 [11049.519242] Lustre: MGC192.168.201.130@tcp: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [11053.432052] Lustre: lustre-MDT0000-mdc-ffff9fc952581800: Connection restored to 192.168.201.130@tcp (at 192.168.201.130@tcp) [11069.155440] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11072.500316] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11076.741587] Lustre: Unmounted lustre-client [11146.596785] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11152.905511] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11172.882179] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11173.304949] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [11176.053582] Lustre: Unmounted lustre-client [11205.035904] Key type lgssc unregistered [11205.266641] LNet: 225961:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11205.276560] LNetError: 225961:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11205.302693] LNet: Removed LNI 192.168.201.30@tcp [11206.065239] Key type .llcrypt unregistered [11206.068434] Key type ._llcrypt unregistered [11221.743251] Key type ._llcrypt registered [11221.744510] Key type .llcrypt registered [11223.053361] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 15:53:33 (1786046013) [11226.813652] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11226.843616] alg: No test for adler32 (adler32-zlib) [11228.039434] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [11228.462318] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [11230.135218] Key type lgssc registered [11231.538427] Lustre: Echo OBD driver; http://www.lustre.org/ [11278.291778] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11285.245000] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11307.344704] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11310.232499] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [11320.917129] Lustre: Unmounted lustre-client [11385.845879] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11391.848543] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11413.688247] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11414.208395] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [11422.799761] Lustre: Unmounted lustre-client [11493.335801] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11499.955061] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11520.398461] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11521.048354] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [11523.469347] Lustre: Unmounted lustre-client [11555.081978] Key type lgssc unregistered [11555.331606] LNet: 233007:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11555.349797] LNetError: 233007:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11555.410293] LNet: Removed LNI 192.168.201.30@tcp [11556.527877] Key type .llcrypt unregistered [11556.530918] Key type ._llcrypt unregistered [11577.209648] Key type ._llcrypt registered [11577.211486] Key type .llcrypt registered [11578.604533] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 15:59:28 (1786046368) [11582.222885] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11582.339710] alg: No test for adler32 (adler32-zlib) [11583.374559] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [11583.629728] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [11585.311202] Key type lgssc registered [11586.756546] Lustre: Echo OBD driver; http://www.lustre.org/ [11630.188315] Key type lgssc unregistered [11630.446968] LNet: 235334:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11630.452202] LNetError: 235334:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11630.466535] LNet: Removed LNI 192.168.201.30@tcp [11631.005893] Key type .llcrypt unregistered [11631.007989] Key type ._llcrypt unregistered [11661.053660] Key type ._llcrypt registered [11661.060764] Key type .llcrypt registered [11661.627397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11661.642576] alg: No test for adler32 (adler32-zlib) [11662.848962] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [11663.300901] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [11665.095254] Key type lgssc registered [11667.004092] Lustre: Echo OBD driver; http://www.lustre.org/ [11753.923884] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11759.764873] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11784.406981] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11827.360671] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11829.353553] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 16:03:39 (1786046619) [11868.438980] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11875.242346] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11897.295305] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11924.044341] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11926.273811] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [11931.623139] Lustre: lustre-OST0001-osc-ffff9fc960caa000: Connection to lustre-OST0001 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [11939.192583] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11949.587320] Lustre: Unmounted lustre-client [12001.674707] Key type lgssc unregistered [12001.890326] LNet: 242464:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12001.905624] LNetError: 242464:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12001.935096] LNet: Removed LNI 192.168.201.30@tcp [12002.681750] Key type .llcrypt unregistered [12002.692694] Key type ._llcrypt unregistered [12033.870384] Key type ._llcrypt registered [12033.873735] Key type .llcrypt registered [12035.367740] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 16:07:04 (1786046824) [12047.785461] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12047.803147] alg: No test for adler32 (adler32-zlib) [12049.040065] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [12049.437618] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [12051.095310] Key type lgssc registered [12052.494437] Lustre: Echo OBD driver; http://www.lustre.org/ [12106.412455] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12113.296113] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12137.264357] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 16:08:47 (1786046927) [12178.750550] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12185.135974] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12207.070721] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12208.657734] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [12223.491705] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12229.037781] Lustre: Unmounted lustre-client [12336.102807] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12343.182834] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12397.800665] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12398.283161] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [12401.466271] Lustre: Unmounted lustre-client [12432.658771] Key type lgssc unregistered [12432.989174] LNet: 250173:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12432.998903] LNetError: 250173:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12433.028098] LNet: Removed LNI 192.168.201.30@tcp [12433.930191] Key type .llcrypt unregistered [12433.932234] Key type ._llcrypt unregistered [12466.239797] Key type ._llcrypt registered [12466.247382] Key type .llcrypt registered [12466.803782] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12466.838343] alg: No test for adler32 (adler32-zlib) [12468.135328] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [12468.748464] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [12470.599155] Key type lgssc registered [12472.337597] Lustre: Echo OBD driver; http://www.lustre.org/ [12560.166360] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12565.961152] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12586.970788] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12630.065647] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12632.127133] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 16:17:01 (1786047421) [12667.796565] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12674.039715] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12695.123060] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12698.887709] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [12701.946635] Lustre: Unmounted lustre-client [12729.813186] Key type lgssc unregistered [12730.136962] LNet: 256812:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12730.153425] LNetError: 256812:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12730.168790] LNet: Removed LNI 192.168.201.30@tcp [12731.195523] Key type .llcrypt unregistered [12731.201491] Key type ._llcrypt unregistered [12749.103304] Key type ._llcrypt registered [12749.104705] Key type .llcrypt registered [12750.607207] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12752.669513] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 16:19:02 (1786047542) [12761.980405] Key type .llcrypt unregistered [12761.982305] Key type ._llcrypt unregistered [12770.106629] Key type ._llcrypt registered [12770.111351] Key type .llcrypt registered [12770.689300] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12770.709964] alg: No test for adler32 (adler32-zlib) [12771.885827] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [12772.211694] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [12773.983553] Key type lgssc registered [12775.554698] Lustre: Echo OBD driver; http://www.lustre.org/ [12829.152278] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12845.643291] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [12848.221725] Lustre: Unmounted lustre-client [12870.399338] Key type lgssc unregistered [12870.731818] LNet: 260268:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12870.746302] LNetError: 260268:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12870.772743] LNet: Removed LNI 192.168.201.30@tcp [12871.677733] Key type .llcrypt unregistered [12871.680542] Key type ._llcrypt unregistered [12888.197726] Key type ._llcrypt registered [12888.199914] Key type .llcrypt registered [12889.945397] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 16:21:19 (1786047679) [12892.890147] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12892.935832] alg: No test for adler32 (adler32-zlib) [12894.058297] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [12894.366734] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [12896.111288] Key type lgssc registered [12897.899461] Lustre: Echo OBD driver; http://www.lustre.org/ [12986.969947] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13024.460614] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13030.356650] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13033.211336] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [13036.651259] Lustre: Unmounted lustre-client [13065.965871] Key type lgssc unregistered [13066.303768] LNet: 263812:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13066.309915] LNetError: 263812:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13066.339805] LNet: Removed LNI 192.168.201.30@tcp [13067.006393] Key type .llcrypt unregistered [13067.009282] Key type ._llcrypt unregistered [13083.208504] Key type ._llcrypt registered [13083.214661] Key type .llcrypt registered [13084.671931] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 16:24:34 (1786047874) [13086.918195] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13087.129963] alg: No test for adler32 (adler32-zlib) [13088.195582] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [13088.452972] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [13090.151366] Key type lgssc registered [13091.468966] Lustre: Echo OBD driver; http://www.lustre.org/ [13144.243264] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13150.976508] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13173.838700] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13176.703826] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [13181.938462] Lustre: lustre-MDT0000-mdc-ffff9fc9464e1800: Connection to lustre-MDT0000 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [13183.124581] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13183.152665] LustreError: 266966:0:(file.c:6192:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13183.705977] LustreError: 266985:0:(file.c:6192:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13183.713366] LustreError: 266985:0:(file.c:6192:ll_inode_revalidate_fini()) Skipped 5 previous similar messages [13183.750593] LustreError: MGC192.168.201.130@tcp: Connection to MGS (at 192.168.201.130@tcp) was lost; in progress operations using this service will fail [13183.822978] Lustre: Unmounted lustre-client [13202.819740] Key type lgssc unregistered [13203.038920] LNet: 267384:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13203.044749] LNetError: 267384:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13203.061873] LNet: Removed LNI 192.168.201.30@tcp [13203.724317] Key type .llcrypt unregistered [13203.726501] Key type ._llcrypt unregistered [13222.269979] Key type ._llcrypt registered [13222.273873] Key type .llcrypt registered [13224.149522] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 16:26:53 (1786048013) [13227.611725] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13227.649516] alg: No test for adler32 (adler32-zlib) [13229.004898] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [13229.532332] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [13231.417978] Key type lgssc registered [13233.343697] Lustre: Echo OBD driver; http://www.lustre.org/ [13288.498123] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13295.370910] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13319.352894] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13321.141218] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [13326.356707] Lustre: lustre-MDT0001-mdc-ffff9fc952f6e000: Connection to lustre-MDT0001 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [13332.369168] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13334.101986] Lustre: Unmounted lustre-client [13355.560422] Key type lgssc unregistered [13355.793238] LNet: 270967:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13355.803581] LNetError: 270967:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13355.830334] LNet: Removed LNI 192.168.201.30@tcp [13356.758360] Key type .llcrypt unregistered [13356.762737] Key type ._llcrypt unregistered [13377.085469] Key type ._llcrypt registered [13377.090173] Key type .llcrypt registered [13379.161345] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 16:29:28 (1786048168) [13398.505288] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13398.514106] alg: No test for adler32 (adler32-zlib) [13399.820924] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [13400.115396] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [13401.815298] Key type lgssc registered [13403.585554] Lustre: Echo OBD driver; http://www.lustre.org/ [13542.113567] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13543.976636] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [13590.532406] Lustre: lustre-MDT0001-mdc-ffff9fc9464e4000: Connection to lustre-MDT0001 (at 192.168.201.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [13609.146235] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13619.594062] Lustre: Unmounted lustre-client [13650.179783] Key type lgssc unregistered [13650.571615] LNet: 276759:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13650.579684] LNetError: 276759:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13650.605373] LNet: Removed LNI 192.168.201.30@tcp [13651.360957] Key type .llcrypt unregistered [13651.368571] Key type ._llcrypt unregistered [13669.809228] Key type ._llcrypt registered [13669.811468] Key type .llcrypt registered [13671.120215] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 16:34:20 (1786048460) [13673.266326] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13675.790594] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 16:34:25 (1786048465) [13677.946833] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13680.063556] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 16:34:29 (1786048469) [13682.490558] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13684.717979] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 16:34:34 (1786048474) [13687.041477] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13688.526952] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 16:34:38 (1786048478) [13690.447504] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13692.623682] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 16:34:42 (1786048482) [13701.805532] Key type .llcrypt unregistered [13701.807944] Key type ._llcrypt unregistered [13710.451124] Key type ._llcrypt registered [13710.456949] Key type .llcrypt registered [13710.981610] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13711.001900] alg: No test for adler32 (adler32-zlib) [13712.270619] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [13712.561600] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [13714.287217] Key type lgssc registered [13716.067331] Lustre: Echo OBD driver; http://www.lustre.org/ [13794.549560] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13801.388754] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13824.498691] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13826.355378] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [13830.887635] Lustre: Unmounted lustre-client [13873.430851] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 16:37:43 (1786048663) [13909.207297] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13916.056859] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13953.530903] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13954.488805] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [13957.825398] Lustre: Unmounted lustre-client [14004.924414] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 16:39:53 (1786048793) [14013.663296] LNet: 1 local NIs in recovery (showing 1): 192.168.201.30@tcp [14669.840868] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14676.798579] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14721.007983] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14838.075815] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 16:53:47 (1786049627) [14852.329633] Key type lgssc unregistered [14852.625922] LNet: 290300:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14852.638074] LNetError: 290300:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14852.676749] LNet: Removed LNI 192.168.201.30@tcp [14853.611819] Key type .llcrypt unregistered [14853.615482] Key type ._llcrypt unregistered [14965.982618] Key type ._llcrypt registered [14966.001949] Key type .llcrypt registered [14966.251900] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14966.273488] alg: No test for adler32 (adler32-zlib) [14967.397420] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [14967.639461] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [14969.321472] Key type lgssc registered [14971.366531] Lustre: Echo OBD driver; http://www.lustre.org/ [15069.756907] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15076.811832] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15101.248691] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15134.996875] Key type lgssc unregistered [15135.304749] LNet: 294004:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15135.315171] LNetError: 294004:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15135.336389] LNet: Removed LNI 192.168.201.30@tcp [15136.246764] Key type .llcrypt unregistered [15136.249694] Key type ._llcrypt unregistered [15174.223691] Key type ._llcrypt registered [15174.224801] Key type .llcrypt registered [15175.346647] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 16:59:25 (1786049965) [15202.376531] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15202.417082] alg: No test for adler32 (adler32-zlib) [15203.620942] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [15203.852798] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [15205.487252] Key type lgssc registered [15207.116494] Lustre: Echo OBD driver; http://www.lustre.org/ [15265.911204] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 17:00:55 (1786050055) [15279.949727] Key type lgssc unregistered [15280.178375] LNet: 297579:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15280.189972] LNetError: 297579:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15280.209822] LNet: Removed LNI 192.168.201.30@tcp [15281.067986] Key type .llcrypt unregistered [15281.071650] Key type ._llcrypt unregistered [15293.069621] Key type ._llcrypt registered [15293.074804] Key type .llcrypt registered [15293.741681] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15293.764869] alg: No test for adler32 (adler32-zlib) [15294.838658] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [15295.071097] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [15296.847552] Key type lgssc registered [15298.129706] Lustre: Echo OBD driver; http://www.lustre.org/ [15356.543662] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15363.370282] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15390.730552] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15675.545747] LNet: Added LNI 192.168.201.30@tcp50 [8/256/0/180] [15678.449961] LNet: Removed LNI 192.168.201.30@tcp [15680.594825] Lustre: 301980:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9fc9526c3000: config has no valid NIDs, force dynamic NIDs [15681.837359] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [15682.019377] Lustre: Unmounted lustre-client [15685.283479] Key type lgssc unregistered [15685.601777] LNet: 302243:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15685.616810] LNetError: 302243:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15685.642189] LNet: Removed LNI 192.168.201.30@tcp50 [15686.429616] Key type .llcrypt unregistered [15686.437032] Key type ._llcrypt unregistered [15705.709579] Key type ._llcrypt registered [15705.715952] Key type .llcrypt registered [15706.319131] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15706.333487] alg: No test for adler32 (adler32-zlib) [15707.537885] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [15707.994645] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [15709.751322] Key type lgssc registered [15711.428061] Lustre: Echo OBD driver; http://www.lustre.org/ [15772.094604] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15777.354473] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15784.905673] LNet: Added LNI 192.168.201.30@tcp50 [8/256/0/180] [15785.722301] LNet: Removed LNI 192.168.201.30@tcp [15786.945612] Lustre: 304934:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9fc95287d800: config has no valid NIDs, force dynamic NIDs [15788.294532] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [15789.150293] Lustre: Unmounted lustre-client [15824.666854] Key type lgssc unregistered [15825.203715] LNet: 305807:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15825.211790] LNetError: 305807:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15825.235681] LNet: Removed LNI 192.168.201.30@tcp50 [15825.900818] Key type .llcrypt unregistered [15825.909573] Key type ._llcrypt unregistered [15840.336505] Key type ._llcrypt registered [15840.339786] Key type .llcrypt registered [15841.916934] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 17:10:31 (1786050631) [15861.133368] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15861.265089] alg: No test for adler32 (adler32-zlib) [15862.507794] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [15862.788554] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [15864.567412] Key type lgssc registered [15866.263657] Lustre: Echo OBD driver; http://www.lustre.org/ [15953.960312] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15960.723922] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15986.547439] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16021.411318] Key type lgssc unregistered [16021.899429] LNet: 310560:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16021.907925] LNetError: 310560:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16021.921491] LNet: Removed LNI 192.168.201.30@tcp [16022.901985] Key type .llcrypt unregistered [16022.904954] Key type ._llcrypt unregistered [16038.187032] Key type ._llcrypt registered [16038.198875] Key type .llcrypt registered [16040.023081] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 17:13:49 (1786050829) [16043.175755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16043.306568] alg: No test for adler32 (adler32-zlib) [16044.555819] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [16044.865382] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [16046.561824] Key type lgssc registered [16047.717108] Lustre: Echo OBD driver; http://www.lustre.org/ [16106.287605] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16113.670186] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16137.564388] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16144.991971] Lustre: Mounted lustre-client - version 2.17.56_52_g55149c9 [16216.459455] Lustre: Mounted fs15246-client - version 2.17.56_52_g55149c9 [16233.656103] Lustre: Unmounted fs15246-client [16265.656753] Lustre: Unmounted lustre-client [16302.461973] Key type lgssc unregistered [16302.782722] LNet: 315428:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16302.794831] LNetError: 315428:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16302.835237] LNet: Removed LNI 192.168.201.30@tcp [16303.604295] Key type .llcrypt unregistered [16303.607365] Key type ._llcrypt unregistered [16315.691743] Key type ._llcrypt registered [16315.694630] Key type .llcrypt registered [16317.105649] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 17:18:26 (1786051106) [16319.434930] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16319.468656] alg: No test for adler32 (adler32-zlib) [16320.691500] Lustre: Lustre: Build Version: 2.17.56_52_g55149c9 [16320.912719] LNet: Added LNI 192.168.201.30@tcp [8/256/0/180] [16322.576293] Key type lgssc registered [16323.977644] Lustre: Echo OBD driver; http://www.lustre.org/ [16486.269689] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16493.666230] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16519.238746] Lustre: DEBUG MARKER: oleg130-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16631.042496] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16300 sec ========= 17:23:40 (1786051420) [16633.250496] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 17:23:42 (1786051422) === [16638.146355] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 17:23:47 (1786051427) === [16676.766453] Key type lgssc unregistered [16677.022341] LNet: 324727:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16677.032490] LNetError: 324727:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16677.056363] LNet: Removed LNI 192.168.201.30@tcp [16677.917891] Key type .llcrypt unregistered [16677.920690] Key type ._llcrypt unregistered