[ 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 426931251 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.002379] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004007] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008011] pid_max: default: 32768 minimum: 301 [ 0.009131] LSM: Security Framework initializing [ 0.010037] Yama: becoming mindful. [ 0.011028] SELinux: Initializing. [ 0.012069] *** VALIDATE selinux *** [ 0.021016] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025655] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026135] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027091] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028077] *** VALIDATE tmpfs *** [ 0.030120] *** VALIDATE proc *** [ 0.031265] *** VALIDATE cgroup *** [ 0.032009] *** VALIDATE cgroup2 *** [ 0.033284] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034148] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036028] Spectre V2 : User space: Vulnerable [ 0.037008] Speculative Store Bypass: Vulnerable [ 0.039742] debug: unmapping init [mem 0xffffffffb4259000-0xffffffffb4260fff] [ 0.041993] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.042561] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.043020] ... version: 2 [ 0.044008] ... bit width: 48 [ 0.045006] ... generic registers: 4 [ 0.045997] ... value mask: 0000ffffffffffff [ 0.047006] ... max period: 00007fffffffffff [ 0.048007] ... fixed-purpose events: 3 [ 0.049006] ... event mask: 000000070000000f [ 0.051209] rcu: Hierarchical SRCU implementation. [ 0.053249] smp: Bringing up secondary CPUs ... [ 0.054534] x86: Booting SMP configuration: [ 0.055020] .... node #0, CPUs: #1 #2 #3 [ 0.058384] smp: Brought up 1 node, 4 CPUs [ 0.060011] smpboot: Max logical packages: 1 [ 0.061025] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.230629] node 0 deferred pages initialised in 168ms [ 0.235153] devtmpfs: initialized [ 0.236215] x86/mm: Memory block size: 128MB [ 0.238692] gcov: version magic: 0x41383552 [ 0.241243] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.242102] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.245375] pinctrl core: initialized pinctrl subsystem [ 0.247202] [ 0.247740] ************************************************************* [ 0.249016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.251014] ** ** [ 0.253012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.255010] ** ** [ 0.257013] ** This means that this kernel is built to expose internal ** [ 0.259011] ** IOMMU data structures, which may compromise security on ** [ 0.261016] ** your system. ** [ 0.263016] ** ** [ 0.265016] ** If you see this message and you are not debugging the ** [ 0.268023] ** kernel, report this immediately to your vendor! ** [ 0.270023] ** ** [ 0.272018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.274012] ************************************************************* [ 0.275698] NET: Registered protocol family 16 [ 0.277434] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.279062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.281063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.285155] cpuidle: using governor menu [ 0.286737] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.288556] PCI: Using configuration type 1 for base access [ 0.290126] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.299063] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.301024] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.303200] cryptd: max_cpu_qlen set to 1000 [ 0.306261] ACPI: Added _OSI(Module Device) [ 0.308030] ACPI: Added _OSI(Processor Device) [ 0.309012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.311011] ACPI: Added _OSI(Processor Aggregator Device) [ 0.315380] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.321508] ACPI: Interpreter enabled [ 0.323060] ACPI: PM: (supports S0 S3 S4 S5) [ 0.324010] ACPI: Using IOAPIC for interrupt routing [ 0.325121] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.329456] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.338486] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.341040] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.343029] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.345085] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.349478] acpiphp: Slot [2] registered [ 0.351109] acpiphp: Slot [5] registered [ 0.353097] acpiphp: Slot [6] registered [ 0.355096] acpiphp: Slot [3] registered [ 0.356128] acpiphp: Slot [4] registered [ 0.358100] acpiphp: Slot [7] registered [ 0.359071] acpiphp: Slot [8] registered [ 0.359954] acpiphp: Slot [9] registered [ 0.361075] acpiphp: Slot [10] registered [ 0.361906] acpiphp: Slot [11] registered [ 0.363076] acpiphp: Slot [12] registered [ 0.364066] acpiphp: Slot [13] registered [ 0.365055] acpiphp: Slot [14] registered [ 0.366078] acpiphp: Slot [15] registered [ 0.368022] acpiphp: Slot [16] registered [ 0.369067] acpiphp: Slot [17] registered [ 0.370046] acpiphp: Slot [18] registered [ 0.371070] acpiphp: Slot [19] registered [ 0.372063] acpiphp: Slot [20] registered [ 0.372961] acpiphp: Slot [21] registered [ 0.374072] acpiphp: Slot [22] registered [ 0.375031] acpiphp: Slot [23] registered [ 0.375990] acpiphp: Slot [24] registered [ 0.377048] acpiphp: Slot [25] registered [ 0.378077] acpiphp: Slot [26] registered [ 0.379047] acpiphp: Slot [27] registered [ 0.379964] acpiphp: Slot [28] registered [ 0.381071] acpiphp: Slot [29] registered [ 0.382053] acpiphp: Slot [30] registered [ 0.383070] acpiphp: Slot [31] registered [ 0.384048] PCI host bridge to bus 0000:00 [ 0.384987] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.387015] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.389011] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.391012] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.392010] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.394015] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.395157] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.397943] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.400961] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.407013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.410482] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.413014] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.415013] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.417015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.420164] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.423013] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.425041] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.427704] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.430843] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.439016] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.443012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.447421] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.457015] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.464980] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.492017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.499395] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.504012] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.508016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.521019] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.530759] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.533327] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.535317] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.537267] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.538150] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.541115] iommu: Default domain type: Passthrough [ 0.543294] SCSI subsystem initialized [ 0.545117] ACPI: bus type USB registered [ 0.547112] usbcore: registered new interface driver usbfs [ 0.549053] usbcore: registered new interface driver hub [ 0.551074] usbcore: registered new device driver usb [ 0.552180] pps_core: LinuxPPS API ver. 1 registered [ 0.554009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.557057] PTP clock support registered [ 0.558124] EDAC MC: Ver: 3.0.0 [ 0.559489] PCI: Using ACPI for IRQ routing [ 0.561000] NetLabel: Initializing [ 0.561000] NetLabel: domain hash size = 128 [ 0.564029] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.565732] NetLabel: unlabeled traffic allowed by default [ 0.569054] vgaarb: loaded [ 0.570376] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.571018] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.578644] clocksource: Switched to clocksource kvm-clock [ 0.682593] VFS: Disk quotas dquot_6.6.0 [ 0.684140] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.686529] *** VALIDATE ramfs *** [ 0.687832] *** VALIDATE hugetlbfs *** [ 0.689276] pnp: PnP ACPI init [ 0.691654] pnp: PnP ACPI: found 6 devices [ 0.707450] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.710611] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.712596] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.713860] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.716327] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.718270] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.720911] NET: Registered protocol family 2 [ 0.723314] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.727127] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.730038] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.734257] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.737281] TCP: Hash tables configured (established 65536 bind 65536) [ 0.739737] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.742724] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.745709] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.748609] NET: Registered protocol family 1 [ 0.751036] RPC: Registered named UNIX socket transport module. [ 0.753458] RPC: Registered udp transport module. [ 0.754961] RPC: Registered tcp transport module. [ 0.756419] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.758644] NET: Registered protocol family 44 [ 0.760157] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.761618] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.763725] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.765777] PCI: CLS 0 bytes, default 64 [ 0.767180] Unpacking initramfs... [ 2.101614] debug: unmapping init [mem 0xffff8c037cc64000-0xffff8c037ffcffff] [ 2.104778] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.106971] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.109199] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.567145] Initialise system trusted keyrings [ 2.568479] Key type blacklist registered [ 2.570172] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.578562] zbud: loaded [ 2.581624] *** VALIDATE nfs *** [ 2.582908] *** VALIDATE nfs4 *** [ 2.584494] pstore: using deflate compression [ 2.587273] Platform Keyring initialized [ 2.668983] NET: Registered protocol family 38 [ 2.670486] Key type asymmetric registered [ 2.671406] Asymmetric key parser 'x509' registered [ 2.672584] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.674754] io scheduler mq-deadline registered [ 2.677011] io scheduler kyber registered [ 2.678908] io scheduler bfq registered [ 2.681330] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.685231] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.688594] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.692113] ACPI: Power Button [PWRF] [ 2.696861] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.703387] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.712302] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.742033] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.771758] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.776956] Non-volatile memory driver v1.3 [ 2.779046] Linux agpgart interface v0.103 [ 2.809174] virtio_blk virtio1: [vda] 139280 512-byte logical blocks (71.3 MB/68.0 MiB) [ 2.811511] vda: detected capacity change from 0 to 71311360 [ 2.828169] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.830526] vdb: detected capacity change from 0 to 1073741824 [ 2.839402] libphy: Fixed MDIO Bus: probed [ 2.843834] usbcore: registered new interface driver usbserial_generic [ 2.845713] usbserial: USB Serial support registered for generic [ 2.847445] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.852269] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.854202] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.856548] mousedev: PS/2 mouse device common for all mice [ 2.859619] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.861585] rtc_cmos 00:05: RTC can wake from S4 [ 2.866517] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.866795] rtc_cmos 00:05: registered as rtc0 [ 2.870855] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.873716] intel_pstate: CPU model not supported [ 2.875398] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.878571] hid: raw HID events driver (C) Jiri Kosina [ 2.880188] usbcore: registered new interface driver usbhid [ 2.881889] usbhid: USB HID core driver [ 2.883101] drop_monitor: Initializing network drop monitor service [ 2.885119] Initializing XFRM netlink socket [ 2.886551] NET: Registered protocol family 10 [ 2.888793] Segment Routing with IPv6 [ 2.890481] NET: Registered protocol family 17 [ 2.892414] mpls_gso: MPLS GSO support [ 2.898742] RAS: Correctable Errors collector initialized. [ 2.900836] AVX version of gcm_enc/dec engaged. [ 2.902153] AES CTR mode by8 optimization enabled [ 2.958530] sched_clock: Marking stable (2958503027, 0)->(3723417904, -764914877) [ 2.961923] registered taskstats version 1 [ 2.963597] Loading compiled-in X.509 certificates [ 2.965034] zswap: loaded using pool lzo/zbud [ 2.983654] Key type big_key registered [ 2.994839] Key type encrypted registered [ 2.996301] ima: No TPM chip found, activating TPM-bypass! [ 2.998226] ima: Allocated hash algorithm: sha1 [ 2.999865] ima: No architecture policies found [ 3.001736] evm: Initialising EVM extended attributes: [ 3.003139] evm: security.selinux [ 3.004258] evm: security.ima [ 3.005433] evm: security.capability [ 3.006844] evm: HMAC attrs: 0x1 [ 3.009179] rtc_cmos 00:05: setting system clock to 2026-06-17 01:47:18 UTC (1781660838) [ 3.016084] debug: unmapping init [mem 0xffffffffb5203000-0xffffffffb53fffff] [ 3.018799] debug: unmapping init [mem 0xffffffffb3f82000-0xffffffffb4258fff] [ 3.031120] Write protecting the kernel read-only data: 28672k [ 3.034264] debug: unmapping init [mem 0xffffffffb2603000-0xffffffffb27fffff] [ 3.036122] debug: unmapping init [mem 0xffffffffb2f14000-0xffffffffb2ffffff] [ 3.064291] 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.071723] systemd[1]: Detected virtualization kvm. [ 3.074059] systemd[1]: Detected architecture x86-64. [ 3.076054] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.105691] systemd[1]: No hostname configured. [ 3.107249] systemd[1]: Set hostname to . [ 3.108899] random: systemd: uninitialized urandom read (16 bytes read) [ 3.111095] systemd[1]: Initializing machine ID from random generator. [ 3.153829] random: ln: uninitialized urandom read (6 bytes read) [ 3.229230] random: systemd: uninitialized urandom read (16 bytes read) [ 3.232764] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.237765] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.241950] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Initrd Root Device. Starting Setup Virtual Console... Starting Create Volatile Files and Directories... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Slices. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Sockets. Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.810952] device-mapper: uevent: version 1.0.3 [ 3.812670] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.454184] virtio_net virtio0 ens2: renamed from eth0 [ 4.491328] scsi host0: ata_piix [ 4.553648] scsi host1: ata_piix [ 4.555070] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.557352] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 5.704018] random: fast init done [ 8.231895] dracut-initqueue[580]: RTNETLINK answers: File exists [ 9.511692] random: crng init done [ 9.513222] 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... [ 9.796767] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ 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 Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. 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... [ 10.753240] printk: systemd: 25 output lines suppressed due to ratelimiting [ 10.969892] SELinux: Disabled at runtime. [ 11.025546] 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.032190] systemd[1]: Detected virtualization kvm. [ 11.033941] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.446175] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.449050] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.452575] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.456184] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.458917] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.465771] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.472694] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. Mounting Huge Pages File System... [ OK ] Listening on udev Control Socket. Mounting POSIX Message Queue File System... [ OK ] Stopped target Switch Root. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target RPC Port Mapper. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target rpc_pipefs.target. Starting udev Coldplug all Devices... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-getty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Local Encrypted Volumes. Starting Remount Root and Kernel File Systems... [ OK ] Stopped target Initrd File Systems. Starting Apply Kernel Variables... [ 11.578986] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Kernel Debug File System... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ 11.844629] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /mnt. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.121794] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.132487] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.235234] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.255806] EDAC sbridge: Ver: 1.1.2 [ 13.102061] Key type dns_resolver registered [ 13.377520] NFS: Registering the id_resolver key type [ 13.379214] Key type id_resolver registered [ 13.380599] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ 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. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ 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 Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg436-client login: [ 38.039778] libcfs: loading out-of-tree module taints kernel. [ 38.192853] Key type ._llcrypt registered [ 38.194319] Key type .llcrypt registered [ 38.516864] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 38.523547] alg: No test for adler32 (adler32-zlib) [ 39.527709] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 39.858697] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 41.487249] Key type lgssc registered [ 42.147643] Lustre: Echo OBD driver; http://www.lustre.org/ [ 101.696190] Lustre: Mounted lustre-client [ 103.922306] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 112.328868] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing check_logdir /tmp/testlogs/ [ 113.721856] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing yml_node [ 115.029350] Lustre: DEBUG MARKER: Client: 2.17.54.40 [ 115.853369] Lustre: DEBUG MARKER: MDS: 2.17.54.40 [ 116.668997] Lustre: DEBUG MARKER: OSS: 2.17.54.40 [ 117.177360] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Jun 16 21:49:12 EDT 2026 [ 122.789542] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 123.339432] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 123.859385] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 134.130673] Lustre: Unmounted lustre-client [ 191.769549] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 204.035214] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 234.862154] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 255.366265] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 21:51:30 (1781661090) [ 256.277745] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 256.948396] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 21:51:31 (1781661091) [ 269.750239] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 271.682734] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 278.891559] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 289.291125] Lustre: Mounted lustre-client [ 291.120187] Lustre: Unmounted lustre-client [ 309.163707] Key type lgssc unregistered [ 309.291582] LNet: 14946:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 309.295296] LNetError: 14946:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 309.304983] LNet: Removed LNI 192.168.204.36@tcp [ 309.599128] Key type .llcrypt unregistered [ 309.600530] Key type ._llcrypt unregistered [ 315.322739] Key type ._llcrypt registered [ 315.324039] Key type .llcrypt registered [ 315.845832] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 21:52:30 (1781661150) [ 316.405120] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 316.413069] alg: No test for adler32 (adler32-zlib) [ 317.290701] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 317.376635] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 318.967135] Key type lgssc registered [ 319.341887] Lustre: Echo OBD driver; http://www.lustre.org/ [ 347.071902] Lustre: Mounted lustre-client [ 424.631179] Lustre: Unmounted lustre-client [ 438.497788] Key type lgssc unregistered [ 438.614770] LNet: 18750:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 438.618227] LNetError: 18750:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 438.627948] LNet: Removed LNI 192.168.204.36@tcp [ 438.918885] Key type .llcrypt unregistered [ 438.920246] Key type ._llcrypt unregistered [ 442.551447] Key type ._llcrypt registered [ 442.553290] Key type .llcrypt registered [ 443.082547] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 21:54:37 (1781661277) [ 443.838737] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 443.844539] alg: No test for adler32 (adler32-zlib) [ 444.689709] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 444.770525] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 446.359176] Key type lgssc registered [ 446.756298] Lustre: Echo OBD driver; http://www.lustre.org/ [ 464.931988] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 466.846488] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 474.100476] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 484.851380] Lustre: Mounted lustre-client [ 488.705144] Lustre: Unmounted lustre-client [ 503.888352] Key type lgssc unregistered [ 504.004506] LNet: 22311:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 504.007129] LNetError: 22311:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 504.016707] LNet: Removed LNI 192.168.204.36@tcp [ 504.218490] Key type .llcrypt unregistered [ 504.219410] Key type ._llcrypt unregistered [ 507.671309] Key type ._llcrypt registered [ 507.672507] Key type .llcrypt registered [ 508.166202] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 21:55:43 (1781661343) [ 508.962601] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 508.974546] alg: No test for adler32 (adler32-zlib) [ 509.832512] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 509.908429] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 511.495162] Key type lgssc registered [ 511.878828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 528.356208] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 530.118524] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 536.542794] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 547.322102] Lustre: Mounted lustre-client [ 550.239094] Lustre: Unmounted lustre-client [ 559.496621] Key type lgssc unregistered [ 559.612610] LNet: 25462:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 559.616238] LNetError: 25462:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 559.624675] LNet: Removed LNI 192.168.204.36@tcp [ 559.838257] Key type .llcrypt unregistered [ 559.839698] Key type ._llcrypt unregistered [ 564.645374] Key type ._llcrypt registered [ 564.646450] Key type .llcrypt registered [ 565.106245] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 21:56:40 (1781661400) [ 565.845818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 565.853322] alg: No test for adler32 (adler32-zlib) [ 566.729179] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 566.821954] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 568.407144] Key type lgssc registered [ 568.786278] Lustre: Echo OBD driver; http://www.lustre.org/ [ 587.995294] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 589.839397] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 596.092982] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 606.703362] Lustre: Mounted lustre-client [ 611.810856] Lustre: lustre-MDT0000-mdc-ffff8c03e0a24800: Connection to lustre-MDT0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 616.929345] Lustre: lustre-MDT0001-mdc-ffff8c03e0a24800: Connection to lustre-MDT0001 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 623.209638] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 633.311126] Lustre: 26418:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781661452/real 1781661452] req@00000000c69106e0 x1868207386269696/t0(0) o400->MGC192.168.204.136@tcp@192.168.204.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1781661468 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 633.319686] LustreError: MGC192.168.204.136@tcp: Connection to MGS (at 192.168.204.136@tcp) was lost; in progress operations using this service will fail [ 633.466147] Lustre: Unmounted lustre-client [ 652.103187] Key type lgssc unregistered [ 652.213403] LNet: 29079:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 652.216370] LNetError: 29079:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 652.225573] LNet: Removed LNI 192.168.204.36@tcp [ 652.460738] Key type .llcrypt unregistered [ 652.461860] Key type ._llcrypt unregistered [ 657.475709] Key type ._llcrypt registered [ 657.476494] Key type .llcrypt registered [ 657.912575] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 21:58:12 (1781661492) [ 658.575987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 658.613817] alg: No test for adler32 (adler32-zlib) [ 659.472263] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 659.549311] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 661.127149] Key type lgssc registered [ 661.498574] Lustre: Echo OBD driver; http://www.lustre.org/ [ 678.249206] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 680.011900] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 686.219354] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 696.813040] Lustre: Mounted lustre-client [ 701.922420] Lustre: lustre-OST0000-osc-ffff8c03d9298000: Connection to lustre-OST0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 705.686558] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 715.963553] Lustre: Unmounted lustre-client [ 732.746750] Key type lgssc unregistered [ 732.856280] LNet: 32651:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 732.858665] LNetError: 32651:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 732.868298] LNet: Removed LNI 192.168.204.36@tcp [ 733.065641] Key type .llcrypt unregistered [ 733.066606] Key type ._llcrypt unregistered [ 738.129415] Key type ._llcrypt registered [ 738.130679] Key type .llcrypt registered [ 738.570748] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 21:59:33 (1781661573) [ 739.329963] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 739.338246] alg: No test for adler32 (adler32-zlib) [ 740.193571] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 740.273100] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 741.855136] Key type lgssc registered [ 742.196501] Lustre: Echo OBD driver; http://www.lustre.org/ [ 759.192960] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 760.916840] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 767.029119] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 777.709173] Lustre: Mounted lustre-client [ 779.482843] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 780.633621] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 791.080856] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-06-1622h00m26s [ 791.719856] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 793.058178] Lustre: lustre-MDT0000-mdc-ffff8c03c4bb9000: Connection to lustre-MDT0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 807.374338] LustreError: 36402:0:(lmv_obd.c:1466:lmv_statfs()) lustre-MDT0000-mdc-ffff8c03c4bb9000: can't stat MDS #0: rc = -19 [ 807.827314] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 810.465025] LustreError: MGC192.168.204.136@tcp: Connection to MGS (at 192.168.204.136@tcp) was lost; in progress operations using this service will fail [ 810.470380] Lustre: Evicted from MGS (at 192.168.204.136@tcp) after server handle changed from 0x2ebe6d4d53f8fd72 to 0x2ebe6d4d53f900f9 [ 810.473189] Lustre: MGC192.168.204.136@tcp: Connection restored to 192.168.204.136@tcp (at 192.168.204.136@tcp) [ 811.489879] LustreError: lustre-MDT0000-mdc-ffff8c03c4bb9000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 811.498305] Lustre: lustre-MDT0000-mdc-ffff8c03c4bb9000: Connection restored to 192.168.204.136@tcp (at 192.168.204.136@tcp) [ 812.459343] Lustre: DEBUG MARKER: Wait for df (36402) ... [ 812.923260] Lustre: DEBUG MARKER: done [ 813.226450] Lustre: Unmounted lustre-client [ 836.485373] Key type lgssc unregistered [ 836.593475] LNet: 37426:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 836.596521] LNetError: 37426:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 836.605682] LNet: Removed LNI 192.168.204.36@tcp [ 836.801676] Key type .llcrypt unregistered [ 836.802557] Key type ._llcrypt unregistered [ 846.194471] Key type ._llcrypt registered [ 846.195503] Key type .llcrypt registered [ 846.632452] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 22:01:21 (1781661681) [ 847.385065] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 847.449328] alg: No test for adler32 (adler32-zlib) [ 848.302884] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 848.383707] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 849.967165] Key type lgssc registered [ 850.336908] Lustre: Echo OBD driver; http://www.lustre.org/ [ 867.553981] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 869.244847] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 875.873655] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 878.074852] Lustre: Mounted lustre-client [ 880.225605] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-06-1622h01m55s [ 880.742734] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 882.986895] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 883.784277] Lustre: DEBUG MARKER: Creating a test file and stat it [ 904.159117] Lustre: 41528:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781661719/real 1781661719] req@00000000e079dab7 x1868207680918272/t0(0) o101->lustre-MDT0000-mdc-ffff8c03ca6fb800@192.168.204.136@tcp:12/10 lens 576/1152 e 0 to 1 dl 1781661739 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 904.166649] Lustre: lustre-MDT0000-mdc-ffff8c03ca6fb800: Connection to lustre-MDT0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 904.177566] Lustre: lustre-MDT0000-mdc-ffff8c03ca6fb800: Connection restored to 192.168.204.136@tcp (at 192.168.204.136@tcp) [ 924.357878] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 925.382474] Lustre: DEBUG MARKER: done [ 926.300144] Lustre: Unmounted lustre-client [ 951.564484] Key type lgssc unregistered [ 951.683804] LNet: 42416:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 951.688550] LNetError: 42416:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 951.699126] LNet: Removed LNI 192.168.204.36@tcp [ 951.947373] Key type .llcrypt unregistered [ 951.948394] Key type ._llcrypt unregistered [ 961.830887] Key type ._llcrypt registered [ 961.831822] Key type .llcrypt registered [ 962.261337] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 22:03:17 (1781661797) [ 962.764560] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 963.321360] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 22:03:18 (1781661798) [ 964.564628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 964.640471] alg: No test for adler32 (adler32-zlib) [ 965.502785] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 965.597324] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 967.175138] Key type lgssc registered [ 967.565513] Lustre: Echo OBD driver; http://www.lustre.org/ [ 978.139986] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 22:03:33 (1781661813) [ 988.649390] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 990.259329] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 996.229831] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 997.363846] Lustre: Mounted lustre-client [ 999.175340] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 999.919158] Lustre: Unmounted lustre-client [ 1009.463290] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1026.303483] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1027.896311] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1028.041052] Lustre: Mounted lustre-client [ 1028.879922] Lustre: Unmounted lustre-client [ 1053.845563] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1055.535626] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1055.682728] Lustre: Mounted lustre-client [ 1056.475877] Lustre: Unmounted lustre-client [ 1067.594509] Lustre: DEBUG MARKER: files compared the same [ 1081.791944] Key type lgssc unregistered [ 1081.901506] LNet: 49949:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1081.903461] LNetError: 49949:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1081.912476] LNet: Removed LNI 192.168.204.36@tcp [ 1082.132125] Key type .llcrypt unregistered [ 1082.132957] Key type ._llcrypt unregistered [ 1087.122328] Key type ._llcrypt registered [ 1087.123845] Key type .llcrypt registered [ 1087.549655] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 22:05:22 (1781661922) [ 1088.377133] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1088.444698] alg: No test for adler32 (adler32-zlib) [ 1089.300098] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 1089.375877] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 1090.959170] Key type lgssc registered [ 1091.343240] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1108.992367] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1110.854856] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1117.546297] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1127.917303] Lustre: Mounted lustre-client [ 1129.552907] Lustre: Unmounted lustre-client [ 1138.663996] Key type lgssc unregistered [ 1138.776458] LNet: 53492:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1138.779898] LNetError: 53492:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1138.788637] LNet: Removed LNI 192.168.204.36@tcp [ 1139.009883] Key type .llcrypt unregistered [ 1139.011560] Key type ._llcrypt unregistered [ 1143.816116] Key type ._llcrypt registered [ 1143.816914] Key type .llcrypt registered [ 1144.183161] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 22:06:19 (1781661979) [ 1144.984540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1145.013297] alg: No test for adler32 (adler32-zlib) [ 1145.878359] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 1145.960112] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 1147.543140] Key type lgssc registered [ 1147.935662] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1207.972412] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1218.074453] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1219.720344] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1244.891941] Key type lgssc unregistered [ 1245.007388] LNet: 56943:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1245.010734] LNetError: 56943:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1245.021283] LNet: Removed LNI 192.168.204.36@tcp [ 1245.233567] Key type .llcrypt unregistered [ 1245.235384] Key type ._llcrypt unregistered [ 1249.896044] Key type ._llcrypt registered [ 1249.896898] Key type .llcrypt registered [ 1250.344272] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 22:08:05 (1781662085) [ 1251.293783] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1251.335605] alg: No test for adler32 (adler32-zlib) [ 1252.190857] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 1252.267418] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 1253.847184] Key type lgssc registered [ 1254.216529] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1279.182269] Lustre: Mounted lustre-client [ 1284.550343] Lustre: Unmounted lustre-client [ 1304.971309] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 22:08:59 (1781662139) [ 1342.867740] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1348.210382] Lustre: Mounted lustre-client [ 1353.531791] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1353.534334] LustreError: 63376:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8c03c3832000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1353.541929] Lustre: 57926:0:(llite_lib.c:4198:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.136@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1363.837172] Lustre: Unmounted lustre-client [ 1390.562459] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 22:10:25 (1781662225) [ 1394.738680] Key type lgssc unregistered [ 1394.851543] LNet: 64585:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1394.853950] LNetError: 64585:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1394.862724] LNet: Removed LNI 192.168.204.36@tcp [ 1395.081189] Key type .llcrypt unregistered [ 1395.082083] Key type ._llcrypt unregistered [ 1399.210373] Key type ._llcrypt registered [ 1399.211288] Key type .llcrypt registered [ 1399.452656] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1399.458071] alg: No test for adler32 (adler32-zlib) [ 1400.325387] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 1400.411278] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 1401.991215] Key type lgssc registered [ 1402.321927] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1418.819766] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1420.447488] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1426.691136] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1437.168667] Lustre: Mounted lustre-client [ 1438.814304] Lustre: Unmounted lustre-client [ 1456.029780] Key type lgssc unregistered [ 1456.138557] LNet: 67558:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1456.141987] LNetError: 67558:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1456.149666] LNet: Removed LNI 192.168.204.36@tcp [ 1456.346202] Key type .llcrypt unregistered [ 1456.347484] Key type ._llcrypt unregistered [ 1459.479550] Key type ._llcrypt registered [ 1459.480848] Key type .llcrypt registered [ 1459.676319] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1459.698904] alg: No test for adler32 (adler32-zlib) [ 1460.552711] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 1460.629276] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 1462.215210] Key type lgssc registered [ 1462.615125] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1509.541951] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1511.251907] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1517.707061] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1528.307267] Lustre: Mounted lustre-client [ 1528.753190] Lustre: Unmounted lustre-client [ 1545.845929] Key type lgssc unregistered [ 1545.956482] LNet: 70947:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1545.958813] LNetError: 70947:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1545.966723] LNet: Removed LNI 192.168.204.36@tcp [ 1546.197640] Key type .llcrypt unregistered [ 1546.198632] Key type ._llcrypt unregistered [ 1550.768933] Key type ._llcrypt registered [ 1550.769855] Key type .llcrypt registered [ 1551.186078] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 22:13:06 (1781662386) [ 1551.825149] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1551.880091] alg: No test for adler32 (adler32-zlib) [ 1552.743652] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 1552.821331] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 1554.399127] Key type lgssc registered [ 1554.757688] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1571.215361] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1572.749559] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1579.330434] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1589.741871] Lustre: Mounted lustre-client [ 1592.014412] Lustre: Unmounted lustre-client [ 1592.119081] Lustre: Mounted lustre-client [ 1592.746257] Lustre: Unmounted lustre-client [ 1601.388712] Key type lgssc unregistered [ 1601.496401] LNet: 74590:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1601.498395] LNetError: 74590:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1601.504576] LNet: Removed LNI 192.168.204.36@tcp [ 1601.714299] Key type .llcrypt unregistered [ 1601.715276] Key type ._llcrypt unregistered [ 1605.498947] Key type ._llcrypt registered [ 1605.499981] Key type .llcrypt registered [ 1605.712727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1605.719142] alg: No test for adler32 (adler32-zlib) [ 1606.583197] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 1606.669487] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 1608.255164] Key type lgssc registered [ 1608.676376] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1626.379194] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1628.113426] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1634.640338] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1645.040332] Lustre: Mounted lustre-client [ 1646.770121] Lustre: Unmounted lustre-client [ 1656.042058] Key type lgssc unregistered [ 1656.158242] LNet: 77588:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1656.160845] LNetError: 77588:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1656.171732] LNet: Removed LNI 192.168.204.36@tcp [ 1656.399748] Key type .llcrypt unregistered [ 1656.401259] Key type ._llcrypt unregistered [ 1661.455081] Key type ._llcrypt registered [ 1661.456350] Key type .llcrypt registered [ 1661.902817] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 22:14:56 (1781662496) [ 1662.720962] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1662.734988] alg: No test for adler32 (adler32-zlib) [ 1663.594241] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 1663.674422] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 1665.247113] Key type lgssc registered [ 1665.622523] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1683.495375] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1685.209605] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1691.652816] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1702.382873] Lustre: Mounted lustre-client [ 1706.097362] Lustre: lustre: root_squash is set to 0:0 [ 1706.104940] Lustre: lustre: nosquash_nids is cleared [ 1715.184483] Lustre: lustre: root_squash is set to 500:500 [ 1721.325240] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.36@tcp 192.168.0.[2,10]@tcp [ 1729.005267] Lustre: lustre: nosquash_nids set to 192.168.204.36/24@tcp [ 1729.662931] Lustre: Unmounted lustre-client [ 1746.496371] Key type lgssc unregistered [ 1746.615515] LNet: 81559:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1746.617658] LNetError: 81559:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1746.627554] LNet: Removed LNI 192.168.204.36@tcp [ 1746.843966] Key type .llcrypt unregistered [ 1746.845263] Key type ._llcrypt unregistered [ 1751.484444] Key type ._llcrypt registered [ 1751.485896] Key type .llcrypt registered [ 1751.910252] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 22:16:26 (1781662586) [ 1752.622763] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 1753.101876] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 22:16:28 (1781662588) [ 1753.715634] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1753.721670] alg: No test for adler32 (adler32-zlib) [ 1754.569236] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 1754.644185] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 1756.223100] Key type lgssc registered [ 1756.543524] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1773.704334] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1775.493423] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1782.005557] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1782.146321] Lustre: lustre: root_squash is set to 500:500 [ 1782.154497] Lustre: lustre: nosquash_nids set to 192.168.204.36/24@tcp [ 1792.494336] Lustre: Mounted lustre-client [ 1794.878747] Lustre: Unmounted lustre-client [ 1803.417679] Key type lgssc unregistered [ 1803.531501] LNet: 85479:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1803.533522] LNetError: 85479:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1803.539544] LNet: Removed LNI 192.168.204.36@tcp [ 1803.748477] Key type .llcrypt unregistered [ 1803.749451] Key type ._llcrypt unregistered [ 1808.403047] Key type ._llcrypt registered [ 1808.404098] Key type .llcrypt registered [ 1808.860765] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 1809.369201] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 22:17:24 (1781662644) [ 1815.140365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1815.169691] alg: No test for adler32 (adler32-zlib) [ 1816.023101] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 1816.105461] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 1817.695152] Key type lgssc registered [ 1818.109612] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1845.838902] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1847.532298] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1855.434658] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1884.826626] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1886.617580] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1893.336432] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1904.382769] Lustre: Mounted lustre-client [ 1916.980660] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8c03c4bbb800.ost_server_uuid 50 [ 1917.446923] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8c03c4bbb800.ost_server_uuid in FULL state after 0 sec [ 1917.545731] Lustre: Mounted lustre-client [ 1918.254168] Lustre: Unmounted lustre-client [ 1945.247472] Key type lgssc unregistered [ 1945.357679] LNet: 93065:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1945.361483] LNetError: 93065:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1945.371837] LNet: Removed LNI 192.168.204.36@tcp [ 1945.600670] Key type .llcrypt unregistered [ 1945.602203] Key type ._llcrypt unregistered [ 1954.993458] Key type ._llcrypt registered [ 1954.994762] Key type .llcrypt registered [ 1955.411643] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 22:19:50 (1781662790) [ 1960.607880] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1960.672750] alg: No test for adler32 (adler32-zlib) [ 1961.520272] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 1961.593765] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 1963.167145] Key type lgssc registered [ 1963.496430] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1991.907706] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1993.671798] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2001.800701] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2008.026285] Lustre: Mounted lustre-client [ 2023.395581] Lustre: lustre-OST0000-osc-ffff8c03c5812800: Connection to lustre-OST0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2028.511161] Lustre: 94424:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781662848/real 1781662848] req@00000000f4944213 x1868208849032704/t0(0) o400->lustre-OST0000-osc-ffff8c03c5812800@192.168.204.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1781662864 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2028.513883] Lustre: lustre-MDT0000-mdc-ffff8c03c5812800: Connection to lustre-MDT0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2033.631137] Lustre: 94424:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781662853/real 1781662853] req@000000000e1b3d42 x1868208849033216/t0(0) o400->lustre-OST0000-osc-ffff8c03c5812800@192.168.204.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1781662869 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2038.752917] LustreError: MGC192.168.204.136@tcp: Connection to MGS (at 192.168.204.136@tcp) was lost; in progress operations using this service will fail [ 2038.758665] Lustre: 97559:0:(mgc_request.c:1901:mgc_process_log()) MGC192.168.204.136@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 2038.760668] Lustre: Evicted from MGS (at 192.168.204.136@tcp) after server handle changed from 0x228c9f16354a96a5 to 0x228c9f16354a99e6 [ 2038.765935] Lustre: MGC192.168.204.136@tcp: Connection restored to 192.168.204.136@tcp (at 192.168.204.136@tcp) [ 2043.238136] Lustre: lustre-MDT0000-mdc-ffff8c03c5812800: Connection restored to 192.168.204.136@tcp (at 192.168.204.136@tcp) [ 2043.641795] Lustre: Unmounted lustre-client [ 2060.750241] Key type lgssc unregistered [ 2060.867576] LNet: 98674:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2060.869758] LNetError: 98674:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2060.877656] LNet: Removed LNI 192.168.204.36@tcp [ 2061.112707] Key type .llcrypt unregistered [ 2061.113887] Key type ._llcrypt unregistered [ 2066.294866] Key type ._llcrypt registered [ 2066.295938] Key type .llcrypt registered [ 2066.763868] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 22:21:41 (1781662901) [ 2078.235351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2078.247269] alg: No test for adler32 (adler32-zlib) [ 2079.102563] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 2079.176438] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 2080.751140] Key type lgssc registered [ 2081.128015] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2095.630295] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2127.410311] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2129.154742] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2137.001875] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2138.176690] Lustre: Mounted lustre-client [ 2138.839268] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2139.401182] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 22:22:54 (1781662974) [ 2189.470024] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2199.733081] Lustre: Unmounted lustre-client [ 2250.059368] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2251.752656] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2259.564473] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2294.247387] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:25:29 (1781663129) [ 2325.338930] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2327.128362] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2334.991456] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2335.144100] Lustre: Mounted lustre-client [ 2336.199237] Lustre: Unmounted lustre-client [ 2362.547893] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:26:37 (1781663197) [ 2392.133027] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2393.844855] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2401.469445] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2406.895465] Lustre: Mounted lustre-client [ 2407.930156] Lustre: Unmounted lustre-client [ 2416.831281] Key type lgssc unregistered [ 2416.948526] LNet: 121490:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2416.950696] LNetError: 121490:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2416.958811] LNet: Removed LNI 192.168.204.36@tcp [ 2417.186099] Key type .llcrypt unregistered [ 2417.187831] Key type ._llcrypt unregistered [ 2422.052679] Key type ._llcrypt registered [ 2422.053624] Key type .llcrypt registered [ 2422.520172] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 22:27:37 (1781663257) [ 2423.250774] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2423.294818] alg: No test for adler32 (adler32-zlib) [ 2424.161509] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 2424.241352] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 2425.823190] Key type lgssc registered [ 2426.183076] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2443.344137] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2445.090922] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2451.658371] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2462.191844] Lustre: Mounted lustre-client [ 2472.876896] Lustre: Unmounted lustre-client [ 2489.907767] Key type lgssc unregistered [ 2490.026347] LNet: 125046:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2490.028655] LNetError: 125046:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2490.037995] LNet: Removed LNI 192.168.204.36@tcp [ 2490.225630] Key type .llcrypt unregistered [ 2490.227067] Key type ._llcrypt unregistered [ 2494.998332] Key type ._llcrypt registered [ 2494.999378] Key type .llcrypt registered [ 2495.420109] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 22:28:50 (1781663330) [ 2496.147593] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2496.154740] alg: No test for adler32 (adler32-zlib) [ 2496.995299] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 2497.072742] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 2498.647157] Key type lgssc registered [ 2498.987496] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2515.662509] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2517.397784] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2523.666468] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2534.383487] Lustre: Mounted lustre-client [ 2539.490615] Lustre: lustre-OST0000-osc-ffff8c03c3836000: Connection to lustre-OST0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2544.852116] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8c03c3836000.ost_server_uuid 50 [ 2545.369023] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 2554.729238] Lustre: Unmounted lustre-client [ 2565.698299] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 22:30:00 (1781663400) [ 2575.453605] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2577.164386] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2583.542704] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2591.168111] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2591.327482] Lustre: Mounted lustre-client [ 2596.322929] Lustre: lustre-OST0000-osc-ffff8c03ca6fe800: Connection to lustre-OST0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2610.682172] Lustre: Unmounted lustre-client [ 2633.531107] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 22:31:08 (1781663468) [ 2643.508257] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2645.218123] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2651.777099] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2658.492299] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2658.653842] Lustre: Mounted lustre-client [ 2663.907642] Lustre: lustre-OST0000-osc-ffff8c03c4060000: Connection to lustre-OST0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2675.421194] Lustre: Unmounted lustre-client [ 2698.703595] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 22:32:13 (1781663533) [ 2727.844830] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2729.524758] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2737.284904] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2774.578350] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2776.248160] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2782.494537] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2803.313853] Lustre: Mounted lustre-client [ 2830.925772] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2831.508098] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 2831.859124] Lustre: Unmounted lustre-client [ 2841.974876] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 22:34:37 (1781663677) [ 2851.927305] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2853.833350] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2860.426869] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2879.487409] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2895.508707] Lustre: Mounted lustre-client [ 2923.204650] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2923.728550] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 2926.562866] Lustre: lustre-OST0001-osc-ffff8c03c3f6b800: Connection to lustre-OST0001 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2931.291913] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2941.563144] Lustre: Unmounted lustre-client [ 2978.949980] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 22:36:53 (1781663813) [ 2989.073286] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2990.793338] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2997.210303] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3002.864338] Lustre: Mounted lustre-client [ 3010.601770] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3011.127130] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3017.736819] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8c03d0840800.ost_server_uuid 50 [ 3018.229374] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8c03d0840800.ost_server_uuid in FULL state after 0 sec [ 3018.892936] Lustre: Unmounted lustre-client [ 3018.978925] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3018.980672] Lustre: Skipped 3 previous similar messages [ 3019.003906] Lustre: Mounted lustre-client [ 3019.376765] LustreError: 148164:0:(sec.c:413:import_sec_validate_get()) import 00000000ff8b7a85 (NEW) with no sec [ 3050.047892] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 22:38:05 (1781663885) [ 3060.263306] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3061.961445] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3068.341492] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3075.065851] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3075.218665] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3080.691310] Lustre: Mounted lustre-client [ 3085.402810] Lustre: Unmounted lustre-client [ 3085.404729] Lustre: Skipped 1 previous similar message [ 3118.144421] Key type lgssc unregistered [ 3118.265678] LNet: 151510:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3118.268761] LNetError: 151510:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3118.278709] LNet: Removed LNI 192.168.204.36@tcp [ 3118.519199] Key type .llcrypt unregistered [ 3118.520399] Key type ._llcrypt unregistered [ 3123.540508] Key type ._llcrypt registered [ 3123.542692] Key type .llcrypt registered [ 3123.975243] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 22:39:18 (1781663958) [ 3124.320903] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3124.357415] alg: No test for adler32 (adler32-zlib) [ 3125.224468] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 3125.315955] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 3126.903186] Key type lgssc registered [ 3127.333144] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3145.623086] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3147.353091] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3153.920240] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3160.390200] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3160.516902] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3165.679484] Lustre: Mounted lustre-client [ 3169.131696] LustreError: lustre-MDT0001-mdc-ffff8c03d929a800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 3169.137575] Lustre: lustre-MDT0001-mdc-ffff8c03d929a800: Connection restored to 192.168.204.136@tcp (at 192.168.204.136@tcp) [ 3170.920023] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3171.435431] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3176.086290] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3182.760950] Lustre: Unmounted lustre-client [ 3201.905721] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 22:40:36 (1781664036) [ 3232.592318] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3234.311771] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3242.049170] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3247.598761] Lustre: Mounted lustre-client [ 3257.976336] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3261.566590] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 3271.139665] Lustre: lustre-OST0001-osc-ffff8c03c3832800: Connection to lustre-OST0001 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3275.891790] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3286.200626] Lustre: Unmounted lustre-client [ 3309.770908] Key type lgssc unregistered [ 3309.886571] LNet: 161021:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3309.889139] LNetError: 161021:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3309.897701] LNet: Removed LNI 192.168.204.36@tcp [ 3310.131277] Key type .llcrypt unregistered [ 3310.132778] Key type ._llcrypt unregistered [ 3320.618337] Key type ._llcrypt registered [ 3320.620043] Key type .llcrypt registered [ 3321.068631] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 22:42:36 (1781664156) [ 3321.936944] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3321.962783] alg: No test for adler32 (adler32-zlib) [ 3322.818773] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 3322.892844] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 3324.471216] Key type lgssc registered [ 3324.891383] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3342.907227] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3344.725572] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3351.432138] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3352.589937] Lustre: Mounted lustre-client [ 3389.275780] Lustre: Unmounted lustre-client [ 3405.892260] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3406.045771] Lustre: Mounted lustre-client [ 3437.875518] Lustre: Unmounted lustre-client [ 3455.378464] Key type lgssc unregistered [ 3455.490498] LNet: 165416:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3455.492734] LNetError: 165416:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3455.502818] LNet: Removed LNI 192.168.204.36@tcp [ 3455.734152] Key type .llcrypt unregistered [ 3455.735134] Key type ._llcrypt unregistered [ 3460.804522] Key type ._llcrypt registered [ 3460.805526] Key type .llcrypt registered [ 3461.270635] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 22:44:56 (1781664296) [ 3461.946971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3461.982321] alg: No test for adler32 (adler32-zlib) [ 3462.829573] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 3462.909166] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 3464.487227] Key type lgssc registered [ 3464.911996] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3482.735255] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3484.474059] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3491.186638] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3501.558692] Lustre: Mounted lustre-client [ 3508.188138] Lustre: Unmounted lustre-client [ 3533.604279] Key type lgssc unregistered [ 3533.710501] LNet: 169134:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3533.713937] LNetError: 169134:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3533.722781] LNet: Removed LNI 192.168.204.36@tcp [ 3533.944365] Key type .llcrypt unregistered [ 3533.946158] Key type ._llcrypt unregistered [ 3537.959237] Key type ._llcrypt registered [ 3537.961643] Key type .llcrypt registered [ 3538.143502] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3538.148157] alg: No test for adler32 (adler32-zlib) [ 3539.011340] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 3539.099588] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 3540.687110] Key type lgssc registered [ 3541.034224] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3558.486936] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3560.245416] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3566.830857] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3577.326376] Lustre: Mounted lustre-client [ 3580.087702] Lustre: Unmounted lustre-client [ 3588.980384] Key type lgssc unregistered [ 3589.086561] LNet: 172163:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3589.089260] LNetError: 172163:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3589.098796] LNet: Removed LNI 192.168.204.36@tcp [ 3589.324325] Key type .llcrypt unregistered [ 3589.326667] Key type ._llcrypt unregistered [ 3594.633857] Key type ._llcrypt registered [ 3594.634930] Key type .llcrypt registered [ 3595.107937] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 22:47:10 (1781664430) [ 3595.876522] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3595.915346] alg: No test for adler32 (adler32-zlib) [ 3596.762292] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 3596.836532] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 3598.415130] Key type lgssc registered [ 3598.836145] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3616.044460] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3617.843825] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3624.140843] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3634.669437] Lustre: Mounted lustre-client [ 3641.290133] Lustre: Unmounted lustre-client [ 3666.754614] Key type lgssc unregistered [ 3666.872429] LNet: 175873:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3666.875082] LNetError: 175873:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3666.881476] LNet: Removed LNI 192.168.204.36@tcp [ 3667.110684] Key type .llcrypt unregistered [ 3667.111716] Key type ._llcrypt unregistered [ 3671.181492] Key type ._llcrypt registered [ 3671.182534] Key type .llcrypt registered [ 3671.347408] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3671.353314] alg: No test for adler32 (adler32-zlib) [ 3672.206667] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 3672.286115] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 3673.871182] Key type lgssc registered [ 3674.235172] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3691.158156] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3693.047641] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3699.693501] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3710.448178] Lustre: Mounted lustre-client [ 3713.245266] Lustre: Unmounted lustre-client [ 3730.278147] Key type lgssc unregistered [ 3730.388388] LNet: 178897:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3730.392837] LNetError: 178897:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3730.403497] LNet: Removed LNI 192.168.204.36@tcp [ 3730.647057] Key type .llcrypt unregistered [ 3730.648247] Key type ._llcrypt unregistered [ 3735.657060] Key type ._llcrypt registered [ 3735.658166] Key type .llcrypt registered [ 3736.106646] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 22:49:31 (1781664571) [ 3742.725876] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3742.795489] alg: No test for adler32 (adler32-zlib) [ 3743.660911] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 3743.740773] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 3745.327114] Key type lgssc registered [ 3745.688405] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3775.627533] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3777.283954] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3785.075408] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3814.589550] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 22:50:49 (1781664649) [ 3825.077870] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3826.847510] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3833.339882] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3844.094205] Lustre: Mounted lustre-client [ 3846.231160] Lustre: Unmounted lustre-client [ 3863.758233] Key type lgssc unregistered [ 3863.874731] LNet: 185918:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3863.878066] LNetError: 185918:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3863.887890] LNet: Removed LNI 192.168.204.36@tcp [ 3864.204237] Key type .llcrypt unregistered [ 3864.206324] Key type ._llcrypt unregistered [ 3870.068354] Key type ._llcrypt registered [ 3870.069382] Key type .llcrypt registered [ 3870.607707] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 3871.342723] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 22:51:46 (1781664706) [ 3878.010879] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3878.054621] alg: No test for adler32 (adler32-zlib) [ 3878.897204] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 3878.971990] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 3880.551169] Key type lgssc registered [ 3880.926739] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3913.119599] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3914.985459] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3923.235749] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 3929.754807] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 3930.911926] Lustre: Mounted lustre-client [ 3937.029158] Lustre: Unmounted lustre-client [ 4002.805572] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4004.529932] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4012.074681] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4028.863115] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 22:54:23 (1781664863) [ 4029.328946] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 4029.841289] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 22:54:24 (1781664864) [ 4071.932710] Key type lgssc unregistered [ 4072.045506] LNet: 197247:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4072.047764] LNetError: 197247:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4072.059806] LNet: Removed LNI 192.168.204.36@tcp [ 4072.265621] Key type .llcrypt unregistered [ 4072.266690] Key type ._llcrypt unregistered [ 4077.112220] Key type ._llcrypt registered [ 4077.113168] Key type .llcrypt registered [ 4077.561364] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 22:55:12 (1781664912) [ 4099.648653] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4099.659425] alg: No test for adler32 (adler32-zlib) [ 4100.507586] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 4100.598564] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 4102.183132] Key type lgssc registered [ 4102.582264] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4117.707710] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4120.034738] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4140.251414] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4160.372687] Key type lgssc unregistered [ 4160.492385] LNet: 200940:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4160.496071] LNetError: 200940:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4160.503338] LNet: Removed LNI 192.168.204.36@tcp [ 4160.808511] Key type .llcrypt unregistered [ 4160.809773] Key type ._llcrypt unregistered [ 4166.878409] Key type ._llcrypt registered [ 4166.879389] Key type .llcrypt registered [ 4167.333118] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 22:56:42 (1781665002) [ 4168.271224] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4168.281306] alg: No test for adler32 (adler32-zlib) [ 4169.137739] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 4169.234498] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 4170.815096] Key type lgssc registered [ 4171.200955] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4191.723663] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4194.217517] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4205.071747] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4211.674138] Lustre: Mounted lustre-client [ 4221.924492] Lustre: lustre-MDT0000-mdc-ffff8c03ca6fa800: Connection to lustre-MDT0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4232.161416] Lustre: lustre-MDT0001-mdc-ffff8c03ca6fa800: Connection to lustre-MDT0001 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4242.402409] LustreError: MGC192.168.204.136@tcp: Connection to MGS (at 192.168.204.136@tcp) was lost; in progress operations using this service will fail [ 4242.413210] Lustre: Evicted from MGS (at 192.168.204.136@tcp) after server handle changed from 0x316cc93bdd8486cf to 0x316cc93bdd84a036 [ 4242.420274] Lustre: MGC192.168.204.136@tcp: Connection restored to 192.168.204.136@tcp (at 192.168.204.136@tcp) [ 4250.597806] Lustre: 204019:0:(mgc_request.c:1901:mgc_process_log()) MGC192.168.204.136@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 4262.091653] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4266.979742] LustreError: lustre-MDT0000-mdc-ffff8c03ca6fa800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 4267.004553] Lustre: lustre-MDT0000-mdc-ffff8c03ca6fa800: Connection restored to 192.168.204.136@tcp (at 192.168.204.136@tcp) [ 4267.951297] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 5 sec [ 4269.730653] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4273.326792] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 3 sec [ 4273.944674] Lustre: Unmounted lustre-client [ 4292.460829] Key type lgssc unregistered [ 4292.566560] LNet: 205902:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4292.568896] LNetError: 205902:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4292.579454] LNet: Removed LNI 192.168.204.36@tcp [ 4292.816862] Key type .llcrypt unregistered [ 4292.817935] Key type ._llcrypt unregistered [ 4297.793388] Key type ._llcrypt registered [ 4297.794356] Key type .llcrypt registered [ 4298.237729] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 22:58:53 (1781665133) [ 4299.012856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4299.030802] alg: No test for adler32 (adler32-zlib) [ 4299.888542] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 4299.962732] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 4301.543163] Key type lgssc registered [ 4302.086629] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4319.972701] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4321.645176] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4342.382519] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4344.024387] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4357.259272] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4364.387793] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4384.833965] Key type lgssc unregistered [ 4384.955502] LNet: 211128:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4384.958093] LNetError: 211128:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4384.967398] LNet: Removed LNI 192.168.204.36@tcp [ 4385.207351] Key type .llcrypt unregistered [ 4385.208572] Key type ._llcrypt unregistered [ 4409.287278] Key type ._llcrypt registered [ 4409.288709] Key type .llcrypt registered [ 4410.039671] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 23:00:44 (1781665244) [ 4425.363402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4425.401682] alg: No test for adler32 (adler32-zlib) [ 4426.266883] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 4426.355231] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 4427.943125] Key type lgssc registered [ 4428.430984] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4459.283575] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4461.050106] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4470.025735] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4498.789723] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 23:02:13 (1781665333) [ 4500.880593] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 23:02:15 (1781665335) [ 4513.232449] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4515.425564] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4523.977456] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4530.647552] Lustre: Mounted lustre-client [ 4537.622217] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 4538.395078] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 4538.958749] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 4539.495693] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 4540.898305] Lustre: lustre-MDT0000-mdc-ffff8c03c32de800: Connection to lustre-MDT0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4556.258983] LustreError: MGC192.168.204.136@tcp: Connection to MGS (at 192.168.204.136@tcp) was lost; in progress operations using this service will fail [ 4556.265589] Lustre: Evicted from MGS (at 192.168.204.136@tcp) after server handle changed from 0x1e5a9a304ecb9f0e to 0x1e5a9a304ecba18b [ 4556.269341] Lustre: MGC192.168.204.136@tcp: Connection restored to 192.168.204.136@tcp (at 192.168.204.136@tcp) [ 4558.700966] Lustre: lustre-MDT0000-mdc-ffff8c03c32de800: Connection restored to 192.168.204.136@tcp (at 192.168.204.136@tcp) [ 4560.402211] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4560.945152] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4562.784687] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 4566.497653] Lustre: lustre-MDT0000-mdc-ffff8c03c32de800: Connection to lustre-MDT0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4581.855144] Lustre: 213026:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781665401/real 1781665401] req@00000000f88edec6 x1868211432734592/t0(0) o400->MGC192.168.204.136@tcp@192.168.204.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1781665417 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4581.860551] LustreError: MGC192.168.204.136@tcp: Connection to MGS (at 192.168.204.136@tcp) was lost; in progress operations using this service will fail [ 4581.884727] Lustre: Evicted from MGS (at 192.168.204.136@tcp) after server handle changed from 0x1e5a9a304ecba18b to 0x1e5a9a304ecba49b [ 4581.892169] Lustre: MGC192.168.204.136@tcp: Connection restored to 192.168.204.136@tcp (at 192.168.204.136@tcp) [ 4584.855378] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4585.638704] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4587.381820] Lustre: Unmounted lustre-client [ 4625.177054] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4627.786120] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4636.058559] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4641.281139] Lustre: Mounted lustre-client [ 4646.673891] Lustre: Unmounted lustre-client [ 4672.556906] Key type lgssc unregistered [ 4672.663557] LNet: 223498:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4672.665551] LNetError: 223498:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4672.677769] LNet: Removed LNI 192.168.204.36@tcp [ 4672.864158] Key type .llcrypt unregistered [ 4672.865076] Key type ._llcrypt unregistered [ 4677.633617] Key type ._llcrypt registered [ 4677.634608] Key type .llcrypt registered [ 4678.064155] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 23:05:13 (1781665513) [ 4679.389297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4679.398692] alg: No test for adler32 (adler32-zlib) [ 4680.266127] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 4680.346226] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 4681.935135] Key type lgssc registered [ 4682.284784] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4699.439069] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4701.096182] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4707.382405] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4718.061552] Lustre: Mounted lustre-client [ 4720.922122] Lustre: Unmounted lustre-client [ 4753.769037] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4755.603859] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4762.026061] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4772.340567] Lustre: Mounted lustre-client [ 4778.397641] Lustre: Unmounted lustre-client [ 4814.042861] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4816.036724] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4824.676047] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4830.211640] Lustre: Mounted lustre-client [ 4830.655123] Lustre: Unmounted lustre-client [ 4841.661333] Key type lgssc unregistered [ 4841.835227] LNet: 230442:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4841.840704] LNetError: 230442:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4841.852144] LNet: Removed LNI 192.168.204.36@tcp [ 4842.267651] Key type .llcrypt unregistered [ 4842.270729] Key type ._llcrypt unregistered [ 4847.961870] Key type ._llcrypt registered [ 4847.963625] Key type .llcrypt registered [ 4848.510962] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 23:08:03 (1781665683) [ 4849.856515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4849.922313] alg: No test for adler32 (adler32-zlib) [ 4850.766388] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 4850.837855] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 4852.415165] Key type lgssc registered [ 4853.037824] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4870.004692] Key type lgssc unregistered [ 4870.122339] LNet: 232755:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4870.124296] LNetError: 232755:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4870.133299] LNet: Removed LNI 192.168.204.36@tcp [ 4870.342384] Key type .llcrypt unregistered [ 4870.343464] Key type ._llcrypt unregistered [ 4879.273377] Key type ._llcrypt registered [ 4879.274321] Key type .llcrypt registered [ 4879.493679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4879.498957] alg: No test for adler32 (adler32-zlib) [ 4880.377939] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 4880.474424] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 4882.063104] Key type lgssc registered [ 4882.416913] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4911.019889] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4912.694345] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4920.689093] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4958.505113] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 4959.023405] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 23:09:54 (1781665794) [ 4969.236750] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4971.128962] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4977.985423] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4985.691041] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4990.958583] Lustre: Mounted lustre-client [ 4996.067306] Lustre: lustre-OST0001-osc-ffff8c03c80f3000: Connection to lustre-OST0001 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4998.547048] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5008.823982] Lustre: Unmounted lustre-client [ 5031.904140] Key type lgssc unregistered [ 5032.026614] LNet: 239785:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5032.029234] LNetError: 239785:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5032.036966] LNet: Removed LNI 192.168.204.36@tcp [ 5032.275794] Key type .llcrypt unregistered [ 5032.277053] Key type ._llcrypt unregistered [ 5042.050843] Key type ._llcrypt registered [ 5042.051774] Key type .llcrypt registered [ 5042.481157] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 23:11:17 (1781665877) [ 5046.578428] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5046.596078] alg: No test for adler32 (adler32-zlib) [ 5047.454795] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 5047.537475] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 5049.127130] Key type lgssc registered [ 5049.498581] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5066.455518] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5068.118130] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5079.128154] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 23:11:54 (1781665914) [ 5088.929225] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5090.735353] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5096.983079] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5098.139197] Lustre: Mounted lustre-client [ 5106.662114] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 5108.198943] Lustre: Unmounted lustre-client [ 5169.507372] hrtimer: interrupt took 4362952 ns [ 5192.153754] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5203.348072] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5262.194448] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5262.967180] Lustre: Mounted lustre-client [ 5266.241319] Lustre: Unmounted lustre-client [ 5309.116503] Key type lgssc unregistered [ 5309.333872] LNet: 247354:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5309.352400] LNetError: 247354:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5309.372603] LNet: Removed LNI 192.168.204.36@tcp [ 5309.938130] Key type .llcrypt unregistered [ 5309.944689] Key type ._llcrypt unregistered [ 5337.540215] Key type ._llcrypt registered [ 5337.587818] Key type .llcrypt registered [ 5337.976781] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5337.988128] alg: No test for adler32 (adler32-zlib) [ 5339.149610] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 5339.488078] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 5341.223461] Key type lgssc registered [ 5342.658609] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5422.692699] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5428.605770] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5453.010393] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5506.108444] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 5508.631300] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 23:19:02 (1781666342) [ 5542.898768] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5550.810314] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5584.426687] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5590.289192] Lustre: Mounted lustre-client [ 5594.111273] Lustre: Unmounted lustre-client [ 5622.698574] Key type lgssc unregistered [ 5622.945913] LNet: 253946:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5622.954335] LNetError: 253946:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5622.969376] LNet: Removed LNI 192.168.204.36@tcp [ 5623.522701] Key type .llcrypt unregistered [ 5623.528326] Key type ._llcrypt unregistered [ 5635.203154] Key type ._llcrypt registered [ 5635.206488] Key type .llcrypt registered [ 5636.096554] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 5637.362780] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 23:21:11 (1781666471) [ 5644.214594] Key type .llcrypt unregistered [ 5644.218297] Key type ._llcrypt unregistered [ 5649.711326] Key type ._llcrypt registered [ 5649.719374] Key type .llcrypt registered [ 5650.054815] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5650.064416] alg: No test for adler32 (adler32-zlib) [ 5651.058436] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 5651.242176] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 5652.911234] Key type lgssc registered [ 5653.819304] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5687.449938] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5699.596592] Lustre: Mounted lustre-client [ 5701.013741] Lustre: Unmounted lustre-client [ 5718.578262] Key type lgssc unregistered [ 5718.772088] LNet: 257387:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5718.780486] LNetError: 257387:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5718.794562] LNet: Removed LNI 192.168.204.36@tcp [ 5719.297559] Key type .llcrypt unregistered [ 5719.303190] Key type ._llcrypt unregistered [ 5729.251031] Key type ._llcrypt registered [ 5729.252651] Key type .llcrypt registered [ 5729.987458] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 23:22:44 (1781666564) [ 5731.233843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5731.255080] alg: No test for adler32 (adler32-zlib) [ 5732.146442] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 5732.291226] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 5733.911320] Key type lgssc registered [ 5734.623460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5802.216120] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5820.419045] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5823.927519] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5826.403885] Lustre: Mounted lustre-client [ 5828.060844] Lustre: Unmounted lustre-client [ 5849.330932] Key type lgssc unregistered [ 5849.499792] LNet: 260897:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5849.505890] LNetError: 260897:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5849.518894] LNet: Removed LNI 192.168.204.36@tcp [ 5849.877799] Key type .llcrypt unregistered [ 5849.879269] Key type ._llcrypt unregistered [ 5858.415545] Key type ._llcrypt registered [ 5858.417546] Key type .llcrypt registered [ 5859.225868] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 23:24:53 (1781666693) [ 5860.567307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5860.588314] alg: No test for adler32 (adler32-zlib) [ 5861.525879] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 5861.661358] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 5863.271213] Key type lgssc registered [ 5863.930906] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5891.578744] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5894.466133] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5904.334226] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5906.614427] Lustre: Mounted lustre-client [ 5912.034693] Lustre: lustre-MDT0000-mdc-ffff8c03c4ca7800: Connection to lustre-MDT0000 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5914.529339] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5914.539549] LustreError: 264026:0:(file.c:6088:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 5914.683430] LustreError: MGC192.168.204.136@tcp: Connection to MGS (at 192.168.204.136@tcp) was lost; in progress operations using this service will fail [ 5914.699942] Lustre: Unmounted lustre-client [ 5925.824247] Key type lgssc unregistered [ 5926.012874] LNet: 264439:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5926.023670] LNetError: 264439:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5926.044955] LNet: Removed LNI 192.168.204.36@tcp [ 5926.516444] Key type .llcrypt unregistered [ 5926.518174] Key type ._llcrypt unregistered [ 5939.369444] Key type ._llcrypt registered [ 5939.371277] Key type .llcrypt registered [ 5940.364336] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 23:26:14 (1781666774) [ 5942.292913] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5942.314665] alg: No test for adler32 (adler32-zlib) [ 5943.305869] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 5943.457047] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 5945.087160] Key type lgssc registered [ 5946.181442] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5979.873266] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5982.560627] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5992.908483] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5994.191777] Lustre: Mounted lustre-client [ 6010.558460] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6011.024911] Lustre: Unmounted lustre-client [ 6022.303546] Key type lgssc unregistered [ 6022.430649] LNet: 267988:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6022.434732] LNetError: 267988:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6022.442983] LNet: Removed LNI 192.168.204.36@tcp [ 6022.686899] Key type .llcrypt unregistered [ 6022.688867] Key type ._llcrypt unregistered [ 6028.419889] Key type ._llcrypt registered [ 6028.421303] Key type .llcrypt registered [ 6028.895718] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 23:27:43 (1781666863) [ 6035.162197] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6035.169938] alg: No test for adler32 (adler32-zlib) [ 6036.043443] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 6036.135276] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 6037.727777] Key type lgssc registered [ 6038.188591] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6077.839646] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6078.999399] Lustre: Mounted lustre-client [ 6094.309844] Lustre: lustre-MDT0001-mdc-ffff8c03c68e3800: Connection to lustre-MDT0001 (at 192.168.204.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6101.287573] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6111.543545] Lustre: Unmounted lustre-client [ 6128.492024] Key type lgssc unregistered [ 6128.596556] LNet: 273746:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6128.599541] LNetError: 273746:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6128.607393] LNet: Removed LNI 192.168.204.36@tcp [ 6128.831355] Key type .llcrypt unregistered [ 6128.832408] Key type ._llcrypt unregistered [ 6134.147299] Key type ._llcrypt registered [ 6134.148283] Key type .llcrypt registered [ 6134.599752] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 23:29:29 (1781666969) [ 6135.127555] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 6135.737819] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 23:29:30 (1781666970) [ 6136.315975] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 6136.833832] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 23:29:31 (1781666971) [ 6137.357287] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 6137.930570] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 23:29:32 (1781666972) [ 6138.470484] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 6138.998328] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 23:29:34 (1781666974) [ 6139.516459] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 6140.048694] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 23:29:35 (1781666975) [ 6143.102611] Key type .llcrypt unregistered [ 6143.103648] Key type ._llcrypt unregistered [ 6145.441037] Key type ._llcrypt registered [ 6145.442790] Key type .llcrypt registered [ 6145.648298] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6145.663967] alg: No test for adler32 (adler32-zlib) [ 6146.514673] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 6146.590106] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 6148.175153] Key type lgssc registered [ 6148.553466] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6174.657447] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6176.386936] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6184.688625] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6185.877663] Lustre: Mounted lustre-client [ 6186.392143] Lustre: Unmounted lustre-client [ 6209.619137] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 23:30:44 (1781667044) [ 6219.456077] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6221.147855] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6240.766330] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6240.940430] Lustre: Mounted lustre-client [ 6241.305123] Lustre: Unmounted lustre-client [ 6271.256286] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 23:31:46 (1781667106) [ 6295.071407] LNet: 1 local NIs in recovery (showing 1): 192.168.204.36@tcp [ 6858.661629] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6860.355589] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6879.914572] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6924.368655] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 23:42:39 (1781667759) [ 6927.725644] Key type lgssc unregistered [ 6927.837370] LNet: 287148:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6927.840983] LNetError: 287148:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6927.852727] LNet: Removed LNI 192.168.204.36@tcp [ 6928.048106] Key type .llcrypt unregistered [ 6928.049197] Key type ._llcrypt unregistered [ 7008.374881] Key type ._llcrypt registered [ 7008.376557] Key type .llcrypt registered [ 7008.520725] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7008.532343] alg: No test for adler32 (adler32-zlib) [ 7009.395709] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 7009.481537] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 7011.071129] Key type lgssc registered [ 7011.464214] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7043.271839] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7045.019673] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7052.962300] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7079.866866] Key type lgssc unregistered [ 7079.991484] LNet: 290800:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7079.993836] LNetError: 290800:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7080.000617] LNet: Removed LNI 192.168.204.36@tcp [ 7080.228469] Key type .llcrypt unregistered [ 7080.229658] Key type ._llcrypt unregistered [ 7097.195106] Key type ._llcrypt registered [ 7097.196160] Key type .llcrypt registered [ 7097.652739] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 23:45:32 (1781667932) [ 7104.321767] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7104.328724] alg: No test for adler32 (adler32-zlib) [ 7105.196175] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 7105.278305] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 7106.863178] Key type lgssc registered [ 7107.235694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7124.157619] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 23:45:59 (1781667959) [ 7128.061118] Key type lgssc unregistered [ 7128.166438] LNet: 294342:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7128.169381] LNetError: 294342:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7128.176522] LNet: Removed LNI 192.168.204.36@tcp [ 7128.409343] Key type .llcrypt unregistered [ 7128.410463] Key type ._llcrypt unregistered [ 7131.989655] Key type ._llcrypt registered [ 7131.991295] Key type .llcrypt registered [ 7132.164188] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7132.169617] alg: No test for adler32 (adler32-zlib) [ 7133.027362] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 7133.105335] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 7134.687256] Key type lgssc registered [ 7135.070132] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7155.813711] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7157.509519] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7165.753372] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7250.449337] LNet: Added LNI 192.168.204.36@tcp50 [8/256/0/180] [ 7251.175613] LNet: Removed LNI 192.168.204.36@tcp [ 7252.522652] Lustre: 298672:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff8c03c7785800: config has no valid NIDs, force dynamic NIDs [ 7253.560753] Lustre: Mounted lustre-client [ 7253.603974] Lustre: Unmounted lustre-client [ 7254.482739] Key type lgssc unregistered [ 7254.627036] LNet: 298931:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7254.629500] LNetError: 298931:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7254.637294] LNet: Removed LNI 192.168.204.36@tcp50 [ 7254.826820] Key type .llcrypt unregistered [ 7254.828421] Key type ._llcrypt unregistered [ 7264.567622] Key type ._llcrypt registered [ 7264.570843] Key type .llcrypt registered [ 7264.747194] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7264.752153] alg: No test for adler32 (adler32-zlib) [ 7265.606445] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 7265.685544] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 7267.271116] Key type lgssc registered [ 7267.630073] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7281.725971] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7283.476089] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7286.104183] LNet: Added LNI 192.168.204.36@tcp50 [8/256/0/180] [ 7286.418363] LNet: Removed LNI 192.168.204.36@tcp [ 7287.467860] Lustre: 301595:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff8c03c6e0c800: config has no valid NIDs, force dynamic NIDs [ 7288.507943] Lustre: Mounted lustre-client [ 7288.657215] Lustre: Unmounted lustre-client [ 7315.752542] Key type lgssc unregistered [ 7315.895172] LNet: 302465:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7315.898647] LNetError: 302465:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7315.906544] LNet: Removed LNI 192.168.204.36@tcp50 [ 7316.110046] Key type .llcrypt unregistered [ 7316.111139] Key type ._llcrypt unregistered [ 7319.594673] Key type ._llcrypt registered [ 7319.595850] Key type .llcrypt registered [ 7320.022801] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 23:49:15 (1781668155) [ 7325.721291] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7325.729233] alg: No test for adler32 (adler32-zlib) [ 7326.589350] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 7326.671847] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 7328.255178] Key type lgssc registered [ 7328.659922] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7357.480147] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7359.248208] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7367.703504] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7386.645406] Key type lgssc unregistered [ 7386.774739] LNet: 307169:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7386.776868] LNetError: 307169:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7386.789684] LNet: Removed LNI 192.168.204.36@tcp [ 7387.046436] Key type .llcrypt unregistered [ 7387.047561] Key type ._llcrypt unregistered [ 7390.822647] Key type ._llcrypt registered [ 7390.823744] Key type .llcrypt registered [ 7391.270706] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 23:50:26 (1781668226) [ 7392.034534] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7392.042040] alg: No test for adler32 (adler32-zlib) [ 7392.900378] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 7392.984809] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 7394.567103] Key type lgssc registered [ 7394.947883] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7412.747346] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7414.567530] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7421.330541] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7431.664882] Lustre: Mounted lustre-client [ 7451.927430] Lustre: Mounted fs15246-client [ 7456.514168] Lustre: Unmounted fs15246-client [ 7469.013117] Lustre: Unmounted lustre-client [ 7486.366240] Key type lgssc unregistered [ 7486.476381] LNet: 311982:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7486.479094] LNetError: 311982:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7486.485847] LNet: Removed LNI 192.168.204.36@tcp [ 7486.719528] Key type .llcrypt unregistered [ 7486.720754] Key type ._llcrypt unregistered [ 7490.480196] Key type ._llcrypt registered [ 7490.481261] Key type .llcrypt registered [ 7490.930434] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 23:52:05 (1781668325) [ 7491.434924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7491.469824] alg: No test for adler32 (adler32-zlib) [ 7492.330983] Lustre: Lustre: Build Version: 2.17.54_40_g6e23802 [ 7492.421286] LNet: Added LNI 192.168.204.36@tcp [8/256/0/180] [ 7494.007122] Key type lgssc registered [ 7494.404905] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7538.757557] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7540.572419] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7548.655885] Lustre: DEBUG MARKER: oleg436-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7596.054018] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7479 sec ========== 23:53:51 (1781668431) [ 7596.577853] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 23:53:51 (1781668431) === [ 7597.664215] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 23:53:52 (1781668432) === [ 7608.576502] Key type lgssc unregistered [ 7608.694500] LNet: 321186:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7608.696872] LNetError: 321186:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7608.706944] LNet: Removed LNI 192.168.204.36@tcp [ 7608.946552] Key type .llcrypt unregistered [ 7608.947798] Key type ._llcrypt unregistered