[ 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.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 468793065 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 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 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: 2889144K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001017] APIC: Switch to symmetric I/O mode setup [ 0.002338] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004012] kvm-guest: setup PV IPIs [ 0.007504] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008022] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009013] pid_max: default: 32768 minimum: 301 [ 0.010147] LSM: Security Framework initializing [ 0.011068] Yama: becoming mindful. [ 0.012044] SELinux: Initializing. [ 0.013066] *** VALIDATE selinux *** [ 0.021049] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025815] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027150] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028134] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030100] *** VALIDATE tmpfs *** [ 0.031479] *** VALIDATE proc *** [ 0.032272] *** VALIDATE cgroup *** [ 0.033015] *** VALIDATE cgroup2 *** [ 0.034259] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035159] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037032] Spectre V2 : User space: Vulnerable [ 0.038018] Speculative Store Bypass: Vulnerable [ 0.041708] debug: unmapping init [mem 0xffffffffb6a59000-0xffffffffb6a60fff] [ 0.044145] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045774] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046028] ... version: 2 [ 0.047013] ... bit width: 48 [ 0.048018] ... generic registers: 4 [ 0.049018] ... value mask: 0000ffffffffffff [ 0.050020] ... max period: 00007fffffffffff [ 0.051017] ... fixed-purpose events: 3 [ 0.052018] ... event mask: 000000070000000f [ 0.053315] rcu: Hierarchical SRCU implementation. [ 0.055455] smp: Bringing up secondary CPUs ... [ 0.056542] x86: Booting SMP configuration: [ 0.057028] .... node #0, CPUs: #1 #2 #3 [ 0.065431] smp: Brought up 1 node, 4 CPUs [ 0.067014] smpboot: Max logical packages: 1 [ 0.068016] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.141560] node 0 deferred pages initialised in 70ms [ 0.144414] devtmpfs: initialized [ 0.145213] x86/mm: Memory block size: 128MB [ 0.147798] gcov: version magic: 0x41383552 [ 0.149156] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.150103] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.151240] pinctrl core: initialized pinctrl subsystem [ 0.152226] [ 0.152791] ************************************************************* [ 0.153018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.154011] ** ** [ 0.155013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.156016] ** ** [ 0.157019] ** This means that this kernel is built to expose internal ** [ 0.158032] ** IOMMU data structures, which may compromise security on ** [ 0.159014] ** your system. ** [ 0.160019] ** ** [ 0.161014] ** If you see this message and you are not debugging the ** [ 0.162015] ** kernel, report this immediately to your vendor! ** [ 0.163016] ** ** [ 0.164018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.165014] ************************************************************* [ 0.166627] NET: Registered protocol family 16 [ 0.167410] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.168066] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.169069] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.170413] cpuidle: using governor menu [ 0.172632] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.173677] PCI: Using configuration type 1 for base access [ 0.174127] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.180123] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.182034] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.186036] cryptd: max_cpu_qlen set to 1000 [ 0.188207] ACPI: Added _OSI(Module Device) [ 0.190014] ACPI: Added _OSI(Processor Device) [ 0.192016] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.194014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.198666] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.204523] ACPI: Interpreter enabled [ 0.205061] ACPI: PM: (supports S0 S3 S4 S5) [ 0.206015] ACPI: Using IOAPIC for interrupt routing [ 0.207104] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.208407] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.217025] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.219042] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.222021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.225086] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.230249] acpiphp: Slot [2] registered [ 0.232171] acpiphp: Slot [3] registered [ 0.234137] acpiphp: Slot [4] registered [ 0.235106] acpiphp: Slot [5] registered [ 0.237228] acpiphp: Slot [6] registered [ 0.239138] acpiphp: Slot [7] registered [ 0.240101] acpiphp: Slot [8] registered [ 0.241099] acpiphp: Slot [9] registered [ 0.243209] acpiphp: Slot [10] registered [ 0.245143] acpiphp: Slot [11] registered [ 0.247137] acpiphp: Slot [12] registered [ 0.249110] acpiphp: Slot [13] registered [ 0.250110] acpiphp: Slot [14] registered [ 0.252092] acpiphp: Slot [15] registered [ 0.253108] acpiphp: Slot [16] registered [ 0.255179] acpiphp: Slot [17] registered [ 0.257169] acpiphp: Slot [18] registered [ 0.258108] acpiphp: Slot [19] registered [ 0.260113] acpiphp: Slot [20] registered [ 0.261110] acpiphp: Slot [21] registered [ 0.263149] acpiphp: Slot [22] registered [ 0.265110] acpiphp: Slot [23] registered [ 0.266100] acpiphp: Slot [24] registered [ 0.267112] acpiphp: Slot [25] registered [ 0.269102] acpiphp: Slot [26] registered [ 0.270104] acpiphp: Slot [27] registered [ 0.272136] acpiphp: Slot [28] registered [ 0.273102] acpiphp: Slot [29] registered [ 0.275097] acpiphp: Slot [30] registered [ 0.276103] acpiphp: Slot [31] registered [ 0.278069] PCI host bridge to bus 0000:00 [ 0.279018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.281025] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.284045] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.286030] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.289025] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.291028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.293221] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.298456] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.302662] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.311018] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.315011] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.318019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.320016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.322021] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.325076] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.327639] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.329034] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.331548] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.334799] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.343997] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.347000] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.350753] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.356043] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.360020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.369000] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.378997] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.384021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.391018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.406026] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.417655] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.420407] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.422482] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.424440] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.427293] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.431179] iommu: Default domain type: Passthrough [ 0.433546] SCSI subsystem initialized [ 0.435216] ACPI: bus type USB registered [ 0.437555] usbcore: registered new interface driver usbfs [ 0.439152] usbcore: registered new interface driver hub [ 0.440132] usbcore: registered new device driver usb [ 0.442179] pps_core: LinuxPPS API ver. 1 registered [ 0.443010] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.446094] PTP clock support registered [ 0.448206] EDAC MC: Ver: 3.0.0 [ 0.450204] PCI: Using ACPI for IRQ routing [ 0.451735] NetLabel: Initializing [ 0.452016] NetLabel: domain hash size = 128 [ 0.453012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.454115] NetLabel: unlabeled traffic allowed by default [ 0.455157] vgaarb: loaded [ 0.456344] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.458024] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.466429] clocksource: Switched to clocksource kvm-clock [ 0.571835] VFS: Disk quotas dquot_6.6.0 [ 0.573287] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.575080] *** VALIDATE ramfs *** [ 0.575918] *** VALIDATE hugetlbfs *** [ 0.577735] pnp: PnP ACPI init [ 0.579753] pnp: PnP ACPI: found 6 devices [ 0.601447] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.604878] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.607314] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.609648] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.612280] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.614690] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.617237] NET: Registered protocol family 2 [ 0.619672] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.624394] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.628053] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.633042] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.636491] TCP: Hash tables configured (established 65536 bind 65536) [ 0.639556] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.642678] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.645401] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.648604] NET: Registered protocol family 1 [ 0.651373] RPC: Registered named UNIX socket transport module. [ 0.653459] RPC: Registered udp transport module. [ 0.655265] RPC: Registered tcp transport module. [ 0.656820] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.659169] NET: Registered protocol family 44 [ 0.660750] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.663076] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.665302] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.667720] PCI: CLS 0 bytes, default 64 [ 0.669449] Unpacking initramfs... [ 2.100683] debug: unmapping init [mem 0xffff9d283cc64000-0xffff9d283ffcffff] [ 2.104330] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.106532] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.109462] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.619372] Initialise system trusted keyrings [ 2.621206] Key type blacklist registered [ 2.623292] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.632179] zbud: loaded [ 2.635728] *** VALIDATE nfs *** [ 2.636953] *** VALIDATE nfs4 *** [ 2.639260] pstore: using deflate compression [ 2.643038] Platform Keyring initialized [ 2.784651] NET: Registered protocol family 38 [ 2.786385] Key type asymmetric registered [ 2.787802] Asymmetric key parser 'x509' registered [ 2.789817] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.792758] io scheduler mq-deadline registered [ 2.794621] io scheduler kyber registered [ 2.796628] io scheduler bfq registered [ 2.799253] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.802886] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.805719] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.808685] ACPI: Power Button [PWRF] [ 2.901303] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.993692] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.096361] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.132535] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.162281] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.168154] Non-volatile memory driver v1.3 [ 3.169588] Linux agpgart interface v0.103 [ 3.205328] virtio_blk virtio1: [vda] 67984 512-byte logical blocks (34.8 MB/33.2 MiB) [ 3.208203] vda: detected capacity change from 0 to 34807808 [ 3.223199] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.226114] vdb: detected capacity change from 0 to 1073741824 [ 3.233471] libphy: Fixed MDIO Bus: probed [ 3.243551] usbcore: registered new interface driver usbserial_generic [ 3.246132] usbserial: USB Serial support registered for generic [ 3.248289] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.252547] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.254355] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.256173] mousedev: PS/2 mouse device common for all mice [ 3.258813] rtc_cmos 00:05: RTC can wake from S4 [ 3.261893] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.262176] rtc_cmos 00:05: registered as rtc0 [ 3.266121] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.268217] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.270138] intel_pstate: CPU model not supported [ 3.274935] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.277602] hid: raw HID events driver (C) Jiri Kosina [ 3.280757] usbcore: registered new interface driver usbhid [ 3.282860] usbhid: USB HID core driver [ 3.284527] drop_monitor: Initializing network drop monitor service [ 3.287057] Initializing XFRM netlink socket [ 3.289143] NET: Registered protocol family 10 [ 3.291425] Segment Routing with IPv6 [ 3.292668] NET: Registered protocol family 17 [ 3.295116] mpls_gso: MPLS GSO support [ 3.300692] RAS: Correctable Errors collector initialized. [ 3.302895] AVX version of gcm_enc/dec engaged. [ 3.304619] AES CTR mode by8 optimization enabled [ 3.384310] sched_clock: Marking stable (3384260926, 0)->(4356474577, -972213651) [ 3.387905] registered taskstats version 1 [ 3.390333] Loading compiled-in X.509 certificates [ 3.392127] zswap: loaded using pool lzo/zbud [ 3.423255] Key type big_key registered [ 3.437814] Key type encrypted registered [ 3.439508] ima: No TPM chip found, activating TPM-bypass! [ 3.441704] ima: Allocated hash algorithm: sha1 [ 3.443435] ima: No architecture policies found [ 3.445325] evm: Initialising EVM extended attributes: [ 3.447175] evm: security.selinux [ 3.448236] evm: security.ima [ 3.449186] evm: security.capability [ 3.450406] evm: HMAC attrs: 0x1 [ 3.453896] rtc_cmos 00:05: setting system clock to 2025-10-24 15:04:52 UTC (1761318292) [ 3.459950] debug: unmapping init [mem 0xffffffffb7a03000-0xffffffffb7bfffff] [ 3.462986] debug: unmapping init [mem 0xffffffffb6782000-0xffffffffb6a58fff] [ 3.472107] Write protecting the kernel read-only data: 28672k [ 3.475495] debug: unmapping init [mem 0xffffffffb4e03000-0xffffffffb4ffffff] [ 3.478461] debug: unmapping init [mem 0xffffffffb5714000-0xffffffffb57fffff] [ 3.514201] 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.521976] systemd[1]: Detected virtualization kvm. [ 3.523897] systemd[1]: Detected architecture x86-64. [ 3.525837] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.553080] systemd[1]: No hostname configured. [ 3.554762] systemd[1]: Set hostname to . [ 3.556921] random: systemd: uninitialized urandom read (16 bytes read) [ 3.559493] systemd[1]: Initializing machine ID from random generator. [ 3.609862] random: ln: uninitialized urandom read (6 bytes read) [ 3.693058] random: systemd: uninitialized urandom read (16 bytes read) [ 3.696127] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.700582] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.706559] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. Starting Journal Service... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories.[ 3.823763] random: fast init done [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Journal Service. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.353062] device-mapper: uevent: version 1.0.3 [ 4.359179] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.117539] virtio_net virtio0 ens2: renamed from eth0 [ 5.172628] scsi host0: ata_piix [ 5.181315] scsi host1: ata_piix [ 5.183036] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.184724] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.846036] dracut-initqueue[577]: RTNETLINK answers: File exists [ 9.956609] random: crng init done [ 9.957824] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.389523] 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... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Stopped target Sockets. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.586743] printk: systemd: 22 output lines suppressed due to ratelimiting [ 11.883718] SELinux: Disabled at runtime. [ 11.950868] 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.958473] systemd[1]: Detected virtualization kvm. [ 11.960520] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.501763] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.505447] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.510389] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.516713] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.519730] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.527927] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.531637] systemd[1]: Stopped target Switch Root. [ OK ] Stopped target Switch Root. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Control Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting Kernel Debug File System... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Mounting POSIX Message Queue File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Huge Pages File System... [ OK ] Reached target RPC Port Mapper. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-sshd\x2dkeygen.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on initctl Compatibility Named Pipe. Starting Remount Root and Kernel File Systems... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Activating swap /dev/disk/by-label/SWAP... Starting Apply Kernel Variables... [ OK ] Created slice system-getty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Journal Service. [ 12.783566] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 13.103571] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.351246] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.479148] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.556871] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.581943] EDAC sbridge: Ver: 1.1.2 [ 14.917519] Key type dns_resolver registered [ 15.225898] NFS: Registering the id_resolver key type [ 15.227914] Key type id_resolver registered [ 15.229530] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... [ 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 ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Login Service. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ 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 OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ 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 System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg458-client login: [ 45.183469] libcfs: loading out-of-tree module taints kernel. [ 45.474326] alg: No test for adler32 (adler32-zlib) [ 46.229331] Key type ._llcrypt registered [ 46.232084] Key type .llcrypt registered [ 46.686313] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 47.065232] hrtimer: interrupt took 4220161 ns [ 47.358938] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 47.936853] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 47.941307] LNet: Accept secure, port 988 [ 49.671136] Key type lgssc registered [ 50.941486] Lustre: Echo OBD driver; http://www.lustre.org/ [ 186.276244] Lustre: Mounted lustre-client [ 190.467252] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 204.601640] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing check_logdir /tmp/testlogs/ [ 208.238770] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing yml_node [ 211.883577] Lustre: DEBUG MARKER: Client: 2.15.7.9 [ 211.935265] Lustre: lustre-OST0000-osc-ffff9d288d471800: disconnect after 23s idle [ 214.158442] Lustre: DEBUG MARKER: MDS: 2.15.7.9 [ 216.093706] Lustre: DEBUG MARKER: OSS: 2.15.7.9 [ 217.626913] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Oct 24 11:08:25 EDT 2025 [ 224.327168] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball 110 [ 225.542607] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 [ 227.500540] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 237.847309] Lustre: Unmounted lustre-client [ 339.133804] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 344.712880] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 365.543639] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 400.160753] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 11:11:27 (1761318687) [ 402.475954] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 404.438809] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 11:11:31 (1761318691) [ 437.696825] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 443.395531] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 460.702848] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 466.981251] Lustre: Mounted lustre-client [ 470.704333] Lustre: Unmounted lustre-client [ 497.171879] Key type lgssc unregistered [ 497.379970] LNet: 14354:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 498.404665] LNet: Removed LNI 192.168.204.58@tcp [ 499.123959] Key type .llcrypt unregistered [ 499.125991] Key type ._llcrypt unregistered [ 512.160630] alg: No test for adler32 (adler32-zlib) [ 512.923315] Key type ._llcrypt registered [ 512.925422] Key type .llcrypt registered [ 514.079094] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 11:13:21 (1761318801) [ 516.720823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 517.102231] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 517.358648] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 517.365076] LNet: Accept secure, port 988 [ 519.043724] Key type lgssc registered [ 520.002414] Lustre: Echo OBD driver; http://www.lustre.org/ [ 562.792359] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 568.121453] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 582.441873] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 593.455192] Lustre: Mounted lustre-client [ 598.178202] Lustre: Unmounted lustre-client [ 622.802430] Key type lgssc unregistered [ 623.004692] LNet: 17936:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 624.034987] LNet: Removed LNI 192.168.204.58@tcp [ 624.668726] Key type .llcrypt unregistered [ 624.670264] Key type ._llcrypt unregistered [ 635.964964] alg: No test for adler32 (adler32-zlib) [ 636.719414] Key type ._llcrypt registered [ 636.721096] Key type .llcrypt registered [ 637.731155] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 11:15:24 (1761318924) [ 639.462563] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 639.767047] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 639.961946] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 639.965062] LNet: Accept secure, port 988 [ 641.647123] Key type lgssc registered [ 642.379706] Lustre: Echo OBD driver; http://www.lustre.org/ [ 683.272021] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 687.799572] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 703.253122] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 714.261075] Lustre: Mounted lustre-client [ 719.334378] Lustre: lustre-MDT0000-mdc-ffff9d288df1e000: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 725.602425] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 731.617544] Lustre: 18873:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761319013/real 1761319013] req@00000000992f9680 x1846876757298112/t0(0) o400->MGC192.168.204.158@tcp@192.168.204.158@tcp:26/25 lens 224/224 e 0 to 1 dl 1761319020 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 731.638274] LustreError: 166-1: MGC192.168.204.158@tcp: Connection to MGS (at 192.168.204.158@tcp) was lost; in progress operations using this service will fail [ 735.918640] Lustre: Unmounted lustre-client [ 753.782835] Key type lgssc unregistered [ 753.956929] LNet: 21558:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 754.979785] LNet: Removed LNI 192.168.204.58@tcp [ 755.487154] Key type .llcrypt unregistered [ 755.488801] Key type ._llcrypt unregistered [ 767.045734] alg: No test for adler32 (adler32-zlib) [ 767.797524] Key type ._llcrypt registered [ 767.799368] Key type .llcrypt registered [ 768.875617] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 11:17:35 (1761319055) [ 770.703836] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 770.943618] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 771.106533] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 771.109560] LNet: Accept secure, port 988 [ 772.721413] Key type lgssc registered [ 773.665332] Lustre: Echo OBD driver; http://www.lustre.org/ [ 814.174880] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 819.271333] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 835.887183] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 846.875433] Lustre: Mounted lustre-client [ 851.953516] Lustre: lustre-OST0000-osc-ffff9d288532f800: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 858.669771] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 869.058230] Lustre: Unmounted lustre-client [ 892.839985] Key type lgssc unregistered [ 893.040621] LNet: 25136:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 894.050315] LNet: Removed LNI 192.168.204.58@tcp [ 894.710200] Key type .llcrypt unregistered [ 894.713233] Key type ._llcrypt unregistered [ 905.406234] alg: No test for adler32 (adler32-zlib) [ 906.158469] Key type ._llcrypt registered [ 906.160138] Key type .llcrypt registered [ 907.250610] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 11:19:54 (1761319194) [ 909.162257] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 909.472996] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 909.707604] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 909.713774] LNet: Accept secure, port 988 [ 911.378627] Key type lgssc registered [ 912.469776] Lustre: Echo OBD driver; http://www.lustre.org/ [ 946.136341] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 951.006646] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 963.814570] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 965.264971] Lustre: Mounted lustre-client [ 971.660293] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 972.919399] LNetError: 120-3: Refusing connection from 192.168.204.58 for 127.0.0.2@tcp: No matching NI [ 972.924736] LNetError: 26066:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 972.931384] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 973.709903] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 984.806428] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-10-2411h21m12s [ 986.079121] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 991.203141] Lustre: lustre-MDT0000-mdc-ffff9d288df1a800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 991.227370] LNetError: 120-3: Refusing connection from 192.168.204.58 for 127.0.0.2@tcp: No matching NI [ 991.232822] LNetError: 26068:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 991.237726] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 994.354954] LustreError: 28883:0:(lmv_obd.c:1273:lmv_statfs()) lustre-MDT0000-mdc-ffff9d288df1a800: can't stat MDS #0: rc = -110 [ 995.238189] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 996.329171] LNetError: 120-3: Refusing connection from 192.168.204.58 for 127.0.0.2@tcp: No matching NI [ 996.334034] LNetError: 26069:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 996.341617] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 1001.450201] LustreError: 166-1: MGC192.168.204.158@tcp: Connection to MGS (at 192.168.204.158@tcp) was lost; in progress operations using this service will fail [ 1001.454579] LustreError: lustre-MDT0000-mdc-ffff9d288df1a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1001.470536] Lustre: lustre-MDT0000-mdc-ffff9d288df1a800: Connection restored to (at 192.168.204.158@tcp) [ 1001.474346] Lustre: Evicted from MGS (at 192.168.204.158@tcp) after server handle changed from 0x9e40348ed860d159 to 0x9e40348ed860d423 [ 1005.619380] Lustre: DEBUG MARKER: Wait for df (28883) ... [ 1006.626076] Lustre: DEBUG MARKER: done [ 1007.492184] Lustre: Unmounted lustre-client [ 1029.600217] Key type lgssc unregistered [ 1029.801286] LNet: 29884:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1030.817594] LNet: Removed LNI 192.168.204.58@tcp [ 1031.225562] Key type .llcrypt unregistered [ 1031.227065] Key type ._llcrypt unregistered [ 1059.116931] alg: No test for adler32 (adler32-zlib) [ 1059.870070] Key type ._llcrypt registered [ 1059.871953] Key type .llcrypt registered [ 1059.993857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1060.195539] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 1060.346172] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 1060.351192] LNet: Accept secure, port 988 [ 1062.007136] Key type lgssc registered [ 1062.803975] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1113.716400] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1116.821436] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1128.965406] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1156.151723] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 11:24:04 (1761319444) [ 1174.746147] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1177.557605] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1186.380544] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1191.932108] Lustre: Mounted lustre-client [ 1194.616341] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-10-2411h24m43s [ 1195.340991] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1198.463516] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1199.578366] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1201.649378] LNetError: 120-3: Refusing connection from 192.168.204.58 for 127.0.0.2@tcp: No matching NI [ 1201.655174] LNetError: 30649:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 1201.658981] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 1220.063186] Lustre: 36888:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761319488/real 1761319488] req@0000000061ae50e9 x1846877197699520/t0(0) o101->lustre-MDT0000-mdc-ffff9d288dfd8000@192.168.204.158@tcp:12/10 lens 576/1152 e 0 to 1 dl 1761319508 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'' [ 1220.075090] Lustre: lustre-MDT0000-mdc-ffff9d288dfd8000: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1220.092347] Lustre: lustre-MDT0000-mdc-ffff9d288dfd8000: Connection restored to (at 192.168.204.158@tcp) [ 1240.450750] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1241.889119] Lustre: DEBUG MARKER: done [ 1243.288224] Lustre: Unmounted lustre-client [ 1262.568961] Key type lgssc unregistered [ 1262.698030] LNet: 37771:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1263.712312] LNet: Removed LNI 192.168.204.58@tcp [ 1263.999482] Key type .llcrypt unregistered [ 1264.001051] Key type ._llcrypt unregistered [ 1285.484225] alg: No test for adler32 (adler32-zlib) [ 1286.237510] Key type ._llcrypt registered [ 1286.239668] Key type .llcrypt registered [ 1286.389346] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1286.566409] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 1286.670623] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 1286.674610] LNet: Accept secure, port 988 [ 1288.279284] Key type lgssc registered [ 1288.779966] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1364.768796] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1369.066856] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1384.665269] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1431.060812] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 11:28:38 (1761319718) [ 1432.285875] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1433.674241] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 11:28:41 (1761319721) [ 1457.235231] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 11:29:05 (1761319745) [ 1483.799402] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1489.277263] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1503.571961] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1514.522781] Lustre: Mounted lustre-client [ 1517.776383] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1521.566857] Lustre: Unmounted lustre-client [ 1531.765687] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1568.713441] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1573.422394] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1573.901729] Lustre: Mounted lustre-client [ 1576.264323] Lustre: Unmounted lustre-client [ 1621.260432] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1625.453403] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1625.868805] Lustre: Mounted lustre-client [ 1628.225308] Lustre: Unmounted lustre-client [ 1647.640899] Lustre: DEBUG MARKER: files compared the same [ 1657.233856] Key type lgssc unregistered [ 1657.409205] LNet: 48987:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1658.465659] LNet: Removed LNI 192.168.204.58@tcp [ 1659.227767] Key type .llcrypt unregistered [ 1659.229703] Key type ._llcrypt unregistered [ 1669.695376] alg: No test for adler32 (adler32-zlib) [ 1670.450453] Key type ._llcrypt registered [ 1670.452063] Key type .llcrypt registered [ 1671.266659] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 11:32:38 (1761319958) [ 1672.779945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1673.080862] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 1673.236286] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 1673.240154] LNet: Accept secure, port 988 [ 1674.879520] Key type lgssc registered [ 1675.642677] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1707.410604] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1711.068991] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1725.354760] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1726.860274] Lustre: Mounted lustre-client [ 1733.274080] Lustre: Unmounted lustre-client [ 1755.750404] Key type lgssc unregistered [ 1755.902047] LNet: 52531:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1756.962269] LNet: Removed LNI 192.168.204.58@tcp [ 1757.341668] Key type .llcrypt unregistered [ 1757.348495] Key type ._llcrypt unregistered [ 1765.671847] alg: No test for adler32 (adler32-zlib) [ 1766.423451] Key type ._llcrypt registered [ 1766.425176] Key type .llcrypt registered [ 1767.190730] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 11:34:14 (1761320054) [ 1768.604967] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1768.777914] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 1768.921815] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 1768.924140] LNet: Accept secure, port 988 [ 1770.535089] Key type lgssc registered [ 1771.356460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1871.126923] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1902.060621] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1905.895907] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1925.895652] Key type lgssc unregistered [ 1926.035083] LNet: 55980:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1926.039680] LNet: Removed LNI 192.168.204.58@tcp [ 1926.366612] Key type .llcrypt unregistered [ 1926.368075] Key type ._llcrypt unregistered [ 1933.565716] alg: No test for adler32 (adler32-zlib) [ 1934.318398] Key type ._llcrypt registered [ 1934.320695] Key type .llcrypt registered [ 1935.005327] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 11:37:02 (1761320222) [ 1936.623912] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1936.777308] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 1936.889266] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 1936.891869] LNet: Accept secure, port 988 [ 1938.495407] Key type lgssc registered [ 1939.046193] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1980.974835] Lustre: Mounted lustre-client [ 1987.462690] Lustre: Unmounted lustre-client [ 2019.861264] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 11:38:28 (1761320308) [ 2087.580559] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2102.275599] Lustre: Mounted lustre-client [ 2107.957089] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2107.960722] LustreError: 62063:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9d288db44000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2107.973479] Lustre: 56944:0:(llite_lib.c:3595:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.158@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2118.272404] Lustre: Unmounted lustre-client [ 2163.897807] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 11:40:52 (1761320452) [ 2170.777860] Key type lgssc unregistered [ 2170.914948] LNet: 63278:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2171.936205] LNet: Removed LNI 192.168.204.58@tcp [ 2172.302764] Key type .llcrypt unregistered [ 2172.304486] Key type ._llcrypt unregistered [ 2178.493667] alg: No test for adler32 (adler32-zlib) [ 2179.247508] Key type ._llcrypt registered [ 2179.249319] Key type .llcrypt registered [ 2179.376337] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2179.531524] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 2179.663982] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 2179.666898] LNet: Accept secure, port 988 [ 2181.287173] Key type lgssc registered [ 2181.867554] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2207.121494] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2209.699634] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2218.903531] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2229.767961] Lustre: Mounted lustre-client [ 2232.210802] Lustre: Unmounted lustre-client [ 2253.452149] Key type lgssc unregistered [ 2253.602817] LNet: 66253:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2254.627811] LNet: Removed LNI 192.168.204.58@tcp [ 2254.989032] Key type .llcrypt unregistered [ 2254.990873] Key type ._llcrypt unregistered [ 2259.856234] alg: No test for adler32 (adler32-zlib) [ 2260.605423] Key type ._llcrypt registered [ 2260.607287] Key type .llcrypt registered [ 2260.738122] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2260.923581] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 2261.054164] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 2261.057416] LNet: Accept secure, port 988 [ 2262.679149] Key type lgssc registered [ 2263.278692] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2319.351924] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2322.244525] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2332.176430] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2342.910512] Lustre: Mounted lustre-client [ 2344.008821] Lustre: Unmounted lustre-client [ 2364.114467] Key type lgssc unregistered [ 2364.255795] LNet: 69628:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2365.281406] LNet: Removed LNI 192.168.204.58@tcp [ 2365.601757] Key type .llcrypt unregistered [ 2365.603412] Key type ._llcrypt unregistered [ 2372.767060] alg: No test for adler32 (adler32-zlib) [ 2373.519434] Key type ._llcrypt registered [ 2373.521099] Key type .llcrypt registered [ 2374.192495] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 11:44:21 (1761320661) [ 2375.263846] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2375.417647] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 2375.534701] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 2375.537739] LNet: Accept secure, port 988 [ 2377.143152] Key type lgssc registered [ 2377.646674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2404.134331] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2407.149521] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2416.591318] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2422.276421] Lustre: Mounted lustre-client [ 2425.191683] Lustre: Unmounted lustre-client [ 2425.455728] Lustre: Mounted lustre-client [ 2426.743341] Lustre: Unmounted lustre-client [ 2455.006175] Key type lgssc unregistered [ 2455.148523] LNet: 73277:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2456.160681] LNet: Removed LNI 192.168.204.58@tcp [ 2456.470341] Key type .llcrypt unregistered [ 2456.471878] Key type ._llcrypt unregistered [ 2462.059628] alg: No test for adler32 (adler32-zlib) [ 2462.811421] Key type ._llcrypt registered [ 2462.813022] Key type .llcrypt registered [ 2462.907300] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2463.054963] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 2463.158516] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 2463.161398] LNet: Accept secure, port 988 [ 2464.767211] Key type lgssc registered [ 2465.298207] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2488.268482] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2490.936867] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2499.193365] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2509.820772] Lustre: Mounted lustre-client [ 2511.915682] Lustre: Unmounted lustre-client [ 2539.434859] Key type lgssc unregistered [ 2539.562288] LNet: 76272:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2540.576877] LNet: Removed LNI 192.168.204.58@tcp [ 2540.852642] Key type .llcrypt unregistered [ 2540.854535] Key type ._llcrypt unregistered [ 2547.443836] alg: No test for adler32 (adler32-zlib) [ 2548.195424] Key type ._llcrypt registered [ 2548.196868] Key type .llcrypt registered [ 2548.918174] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 11:47:16 (1761320836) [ 2550.012212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2550.212963] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 2550.333824] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 2550.336956] LNet: Accept secure, port 988 [ 2551.951165] Key type lgssc registered [ 2552.479057] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2576.341992] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2578.970698] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2587.842963] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2598.422362] Lustre: Mounted lustre-client [ 2604.801457] Lustre: lustre: root_squash is set to 0:0 [ 2604.813515] Lustre: lustre: nosquash_nids is cleared [ 2614.786265] Lustre: lustre: root_squash is set to 500:500 [ 2625.016088] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.58@tcp 192.168.0.[2,10]@tcp [ 2626.106224] Lustre: Unmounted lustre-client [ 2644.934381] Key type lgssc unregistered [ 2645.045941] LNet: 80133:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2646.112290] LNet: Removed LNI 192.168.204.58@tcp [ 2646.433261] Key type .llcrypt unregistered [ 2646.435073] Key type ._llcrypt unregistered [ 2653.047764] alg: No test for adler32 (adler32-zlib) [ 2653.799320] Key type ._llcrypt registered [ 2653.800971] Key type .llcrypt registered [ 2654.468263] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 11:49:02 (1761320942) [ 2655.647861] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2656.433158] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 11:49:04 (1761320944) [ 2657.572257] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2657.726540] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 2657.828912] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 2657.832318] LNet: Accept secure, port 988 [ 2659.431126] Key type lgssc registered [ 2659.981302] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2682.920748] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2685.528463] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2693.883674] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2694.112990] Lustre: lustre: root_squash is set to 500:500 [ 2694.130609] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.58@tcp 192.168.0.[2,10]@tcp [ 2704.370542] Lustre: Mounted lustre-client [ 2707.308384] Lustre: Unmounted lustre-client [ 2734.223157] Key type lgssc unregistered [ 2734.349367] LNet: 84055:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2735.391843] LNet: Removed LNI 192.168.204.58@tcp [ 2735.711742] Key type .llcrypt unregistered [ 2735.713350] Key type ._llcrypt unregistered [ 2741.969634] alg: No test for adler32 (adler32-zlib) [ 2742.721375] Key type ._llcrypt registered [ 2742.722980] Key type .llcrypt registered [ 2743.351049] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2744.060935] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 11:50:32 (1761321032) [ 2752.785830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2752.963763] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 2753.072975] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 2753.076161] LNet: Accept secure, port 988 [ 2754.679193] Key type lgssc registered [ 2755.124836] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2791.093604] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2793.499522] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2802.883806] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2833.972343] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2836.484466] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2844.803850] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2857.182193] Lustre: Mounted lustre-client [ 2874.461680] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d28a0506000.ost_server_uuid 40 [ 2875.089856] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d28a0506000.ost_server_uuid in FULL state after 0 sec [ 2875.258691] Lustre: Mounted lustre-client [ 2876.292168] Lustre: Unmounted lustre-client [ 2922.860866] Key type lgssc unregistered [ 2922.995749] LNet: 91724:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2923.000394] LNet: Removed LNI 192.168.204.58@tcp [ 2923.321722] Key type .llcrypt unregistered [ 2923.324442] Key type ._llcrypt unregistered [ 2942.392641] alg: No test for adler32 (adler32-zlib) [ 2943.145445] Key type ._llcrypt registered [ 2943.146935] Key type .llcrypt registered [ 2943.232376] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2943.374661] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 2943.469105] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 2943.471494] LNet: Accept secure, port 988 [ 2945.063133] Key type lgssc registered [ 2945.545922] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2980.195746] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2982.404657] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2991.602878] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3011.189466] Lustre: DEBUG MARKER: == conf-sanity test 47: server restart does not make client loss lru_resize settings ========================================================== 11:54:59 (1761321299) [ 3048.089665] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3050.313673] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3059.334828] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3064.818103] Lustre: Mounted lustre-client [ 3076.063134] Lustre: 92501:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761321358/real 1761321358] req@00000000809ac8e2 x1846879172168512/t0(0) o400->lustre-OST0000-osc-ffff9d288d895800@192.168.204.158@tcp:28/4 lens 224/224 e 0 to 1 dl 1761321365 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 3076.072765] Lustre: lustre-OST0000-osc-ffff9d288d895800: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3082.208545] Lustre: 92501:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761321364/real 1761321364] req@00000000a07bfb9c x1846879172168768/t0(0) o400->lustre-OST0000-osc-ffff9d288d895800@192.168.204.158@tcp:28/4 lens 224/224 e 0 to 1 dl 1761321371 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 3082.221743] Lustre: lustre-OST0000-osc-ffff9d288d895800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 3087.330119] Lustre: lustre-MDT0000-mdc-ffff9d288d895800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3092.959167] Lustre: 99615:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761321374/real 1761321374] req@00000000188dd8f8 x1846879172169664/t0(0) o101->MGC192.168.204.158@tcp@192.168.204.158@tcp:26/25 lens 328/344 e 0 to 1 dl 1761321381 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'' [ 3092.968991] LustreError: 166-1: MGC192.168.204.158@tcp: Connection to MGS (at 192.168.204.158@tcp) was lost; in progress operations using this service will fail [ 3099.107228] Lustre: Evicted from MGS (at 192.168.204.158@tcp) after server handle changed from 0x9b3df1f6c7c11558 to 0x9b3df1f6c7c119aa [ 3099.111891] Lustre: MGC192.168.204.158@tcp: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 3104.069373] Lustre: lustre-MDT0000-mdc-ffff9d288d895800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 3104.497440] Lustre: Unmounted lustre-client [ 3122.408965] Key type lgssc unregistered [ 3122.513496] LNet: 100683:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3123.552052] LNet: Removed LNI 192.168.204.58@tcp [ 3123.817103] Key type .llcrypt unregistered [ 3123.818561] Key type ._llcrypt unregistered [ 3129.939329] alg: No test for adler32 (adler32-zlib) [ 3130.691327] Key type ._llcrypt registered [ 3130.692701] Key type .llcrypt registered [ 3131.276296] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 11:56:58 (1761321418) [ 3132.207402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3132.398190] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 3132.489906] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 3132.493159] LNet: Accept secure, port 988 [ 3134.087144] Key type lgssc registered [ 3134.527893] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3156.628283] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3159.038371] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3166.730455] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3177.455131] Lustre: Mounted lustre-client [ 3310.563672] Lustre: lustre-MDT0000-mdc-ffff9d288d967800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3317.727165] Lustre: 101650:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761321599/real 1761321599] req@0000000081a2e3fe x1846879371786496/t0(0) o400->MGC192.168.204.158@tcp@192.168.204.158@tcp:26/25 lens 224/224 e 0 to 1 dl 1761321606 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 3317.736512] LustreError: 166-1: MGC192.168.204.158@tcp: Connection to MGS (at 192.168.204.158@tcp) was lost; in progress operations using this service will fail [ 3323.879134] Lustre: Evicted from MGS (at 192.168.204.158@tcp) after server handle changed from 0x4568a9eac3814bea to 0x4568a9eac383b58e [ 3323.884118] Lustre: MGC192.168.204.158@tcp: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 3332.420569] Lustre: lustre-MDT0000-mdc-ffff9d288d967800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 3334.845653] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3335.546485] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3337.323683] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3347.574172] Lustre: Unmounted lustre-client [ 3405.078292] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3407.268177] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3416.008650] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3434.596716] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 12:02:03 (1761321723) [ 3472.666231] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3477.381483] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3499.503330] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3500.218780] Lustre: Mounted lustre-client [ 3504.575332] Lustre: Unmounted lustre-client [ 3534.869213] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 12:03:42 (1761321822) [ 3591.987708] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3596.111300] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3609.636274] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3609.992371] Lustre: Mounted lustre-client [ 3612.267853] Lustre: Unmounted lustre-client [ 3632.146823] Key type lgssc unregistered [ 3632.266879] LNet: 121038:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3633.311981] LNet: Removed LNI 192.168.204.58@tcp [ 3633.596663] Key type .llcrypt unregistered [ 3633.598619] Key type ._llcrypt unregistered [ 3639.864853] alg: No test for adler32 (adler32-zlib) [ 3640.618410] Key type ._llcrypt registered [ 3640.619817] Key type .llcrypt registered [ 3641.235674] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 12:05:28 (1761321928) [ 3642.264207] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3642.410790] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 3642.540436] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 3642.542996] LNet: Accept secure, port 988 [ 3644.143150] Key type lgssc registered [ 3644.754530] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3675.476854] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3679.411605] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3690.556871] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3691.940541] Lustre: Mounted lustre-client [ 3712.079967] Lustre: Unmounted lustre-client [ 3739.182840] Key type lgssc unregistered [ 3739.294638] LNet: 124605:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3740.320200] LNet: Removed LNI 192.168.204.58@tcp [ 3740.608633] Key type .llcrypt unregistered [ 3740.609925] Key type ._llcrypt unregistered [ 3747.069980] alg: No test for adler32 (adler32-zlib) [ 3747.825548] Key type ._llcrypt registered [ 3747.827381] Key type .llcrypt registered [ 3748.510382] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 12:07:16 (1761322036) [ 3749.549886] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3749.723372] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 3749.868156] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 3749.870520] LNet: Accept secure, port 988 [ 3751.495146] Key type lgssc registered [ 3752.001574] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3773.911874] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3776.169543] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3783.411571] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3784.624391] Lustre: Mounted lustre-client [ 3789.795345] Lustre: lustre-OST0000-osc-ffff9d288c471800: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3795.856189] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9d288c471800.ost_server_uuid 40 [ 3796.448769] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 3805.910062] Lustre: Unmounted lustre-client [ 3817.914182] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 12:08:26 (1761322106) [ 3831.049688] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3833.533727] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3841.318228] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3851.336570] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3851.617467] Lustre: Mounted lustre-client [ 3856.867962] Lustre: lustre-OST0000-osc-ffff9d288d473800: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3881.185248] Lustre: Unmounted lustre-client [ 3916.551923] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 12:10:05 (1761322205) [ 3929.890883] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3932.281894] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3940.547321] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3948.544631] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3948.775762] Lustre: Mounted lustre-client [ 3954.147677] Lustre: lustre-OST0000-osc-ffff9d28911d9000: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3961.969527] Lustre: Unmounted lustre-client [ 3987.678740] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 12:11:16 (1761322276) [ 4025.002777] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4027.409169] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4036.806276] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4070.837111] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4073.368420] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4081.796400] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4103.274836] Lustre: Mounted lustre-client [ 4132.202808] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4134.986881] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 2 sec [ 4135.474621] Lustre: Unmounted lustre-client [ 4163.485788] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 12:14:11 (1761322451) [ 4176.686277] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4179.049471] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4186.847563] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4208.108252] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4223.245317] Lustre: Mounted lustre-client [ 4251.839656] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4252.526591] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4254.182341] Lustre: lustre-OST0001-osc-ffff9d288c264800: Connection to lustre-OST0001 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4256.577747] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4266.876236] Lustre: Unmounted lustre-client [ 4305.984310] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 12:16:34 (1761322594) [ 4317.061615] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4319.294119] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4326.349217] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4326.541994] Lustre: Mounted lustre-client [ 4334.614845] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4335.212941] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4340.545070] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d288532f800.ost_server_uuid 40 [ 4341.116038] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d288532f800.ost_server_uuid in FULL state after 0 sec [ 4341.806181] Lustre: Unmounted lustre-client [ 4341.911648] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4341.915293] Lustre: Skipped 3 previous similar messages [ 4342.360705] LustreError: 148410:0:(sec.c:411:import_sec_validate_get()) import 00000000b0ff0fcc (NEW) with no sec [ 4382.446783] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 12:17:50 (1761322670) [ 4394.379437] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4396.393044] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4403.458741] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4410.705239] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4410.858085] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 4415.982792] Lustre: Mounted lustre-client [ 4415.983759] Lustre: Skipped 1 previous similar message [ 4419.814986] LustreError: 151408:0:(obd_config.c:1999:class_config_llog_handler()) MGC192.168.204.158@tcp: cfg command failed: rc = -114 [ 4419.819095] Lustre: cmd=cf00f 0:lustre-OST0000-osc 1:osc.active=1 [ 4419.819095] [ 4419.822347] LustreError: 151327:0:(mgc_request.c:614:do_requeue()) failed processing log: -114 [ 4422.861390] Lustre: Unmounted lustre-client [ 4422.862673] Lustre: Skipped 1 previous similar message [ 4443.624259] Key type lgssc unregistered [ 4443.727340] LNet: 151891:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4444.768770] LNet: Removed LNI 192.168.204.58@tcp [ 4445.001149] Key type .llcrypt unregistered [ 4445.002203] Key type ._llcrypt unregistered [ 4450.061200] alg: No test for adler32 (adler32-zlib) [ 4450.812468] Key type ._llcrypt registered [ 4450.813948] Key type .llcrypt registered [ 4451.299884] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 12:18:59 (1761322739) [ 4451.787178] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4451.939470] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 4452.011654] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 4452.013444] LNet: Accept secure, port 988 [ 4453.599128] Key type lgssc registered [ 4454.009044] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4473.294103] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4475.309362] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4481.983920] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4488.221725] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4488.362347] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4489.390436] Lustre: Mounted lustre-client [ 4494.828797] LustreError: lustre-MDT0001-mdc-ffff9d288532c800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 4494.835631] Lustre: lustre-MDT0001-mdc-ffff9d288532c800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 4496.916106] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4497.508145] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4503.333819] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4509.665365] Lustre: Unmounted lustre-client [ 4537.552546] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 12:20:25 (1761322825) [ 4609.444869] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4612.558842] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4623.864596] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4624.184918] Lustre: Mounted lustre-client [ 4638.648845] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4639.557541] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4647.923865] Lustre: lustre-OST0001-osc-ffff9d28876c8000: Connection to lustre-OST0001 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4653.941403] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4664.260798] Lustre: Unmounted lustre-client [ 4699.575292] Key type lgssc unregistered [ 4699.711711] LNet: 161405:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4700.768623] LNet: Removed LNI 192.168.204.58@tcp [ 4701.068928] Key type .llcrypt unregistered [ 4701.070598] Key type ._llcrypt unregistered [ 4726.282291] alg: No test for adler32 (adler32-zlib) [ 4727.042661] Key type ._llcrypt registered [ 4727.044432] Key type .llcrypt registered [ 4727.328257] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4727.586152] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 4727.758601] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 4727.763062] LNet: Accept secure, port 988 [ 4729.407226] Key type lgssc registered [ 4730.163853] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4781.757025] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4785.395476] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4799.365727] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4844.342491] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 12:25:32 (1761323132) [ 4861.195411] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4864.448310] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4873.828583] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4879.372354] Lustre: Mounted lustre-client [ 4884.914836] Lustre: Unmounted lustre-client [ 4901.096847] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4901.320948] Lustre: Mounted lustre-client [ 4904.345346] Lustre: Unmounted lustre-client [ 4920.170215] Key type lgssc unregistered [ 4920.293295] LNet: 168732:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4921.312147] LNet: Removed LNI 192.168.204.58@tcp [ 4921.631569] Key type .llcrypt unregistered [ 4921.633051] Key type ._llcrypt unregistered [ 4929.760770] alg: No test for adler32 (adler32-zlib) [ 4930.513406] Key type ._llcrypt registered [ 4930.514812] Key type .llcrypt registered [ 4931.303391] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 12:26:58 (1761323218) [ 4932.469199] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4932.687664] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 4932.799989] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 4932.802437] LNet: Accept secure, port 988 [ 4934.407182] Key type lgssc registered [ 4934.973555] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4962.660592] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4966.092939] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4976.990642] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4983.801896] Lustre: Mounted lustre-client [ 4997.636108] Lustre: Unmounted lustre-client [ 5016.671118] Key type lgssc unregistered [ 5016.799529] LNet: 172441:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5017.824314] LNet: Removed LNI 192.168.204.58@tcp [ 5018.158876] Key type .llcrypt unregistered [ 5018.161241] Key type ._llcrypt unregistered [ 5023.836371] alg: No test for adler32 (adler32-zlib) [ 5024.588380] Key type ._llcrypt registered [ 5024.589983] Key type .llcrypt registered [ 5024.719447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5024.874424] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 5024.984791] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 5024.987743] LNet: Accept secure, port 988 [ 5026.591138] Key type lgssc registered [ 5027.223401] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5053.432519] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5056.202714] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5065.545406] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5071.351382] Lustre: Mounted lustre-client [ 5074.610056] Lustre: Unmounted lustre-client [ 5090.047325] Key type lgssc unregistered [ 5090.185894] LNet: 175460:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5091.232163] LNet: Removed LNI 192.168.204.58@tcp [ 5091.525781] Key type .llcrypt unregistered [ 5091.527491] Key type ._llcrypt unregistered [ 5096.898793] alg: No test for adler32 (adler32-zlib) [ 5097.651399] Key type ._llcrypt registered [ 5097.652619] Key type .llcrypt registered [ 5097.758596] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5097.903755] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 5098.016269] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 5098.018892] LNet: Accept secure, port 988 [ 5099.623147] Key type lgssc registered [ 5100.149797] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5123.006158] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5125.341386] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5132.997844] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5143.559352] Lustre: Mounted lustre-client [ 5145.656401] Lustre: Unmounted lustre-client [ 5161.090782] Key type lgssc unregistered [ 5161.202536] LNet: 178430:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5162.209091] LNet: Removed LNI 192.168.204.58@tcp [ 5162.532509] Key type .llcrypt unregistered [ 5162.534231] Key type ._llcrypt unregistered [ 5168.531198] alg: No test for adler32 (adler32-zlib) [ 5169.283416] Key type ._llcrypt registered [ 5169.285102] Key type .llcrypt registered [ 5169.867869] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 12:30:57 (1761323457) [ 5170.990141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5171.134742] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 5171.246889] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 5171.249704] LNet: Accept secure, port 988 [ 5172.863139] Key type lgssc registered [ 5173.340436] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5196.591145] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5198.830322] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5206.610441] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5207.803929] Lustre: Mounted lustre-client [ 5216.472531] Lustre: Unmounted lustre-client [ 5231.123769] Key type lgssc unregistered [ 5231.239451] LNet: 182142:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5232.288248] LNet: Removed LNI 192.168.204.58@tcp [ 5232.539544] Key type .llcrypt unregistered [ 5232.541230] Key type ._llcrypt unregistered [ 5237.126901] alg: No test for adler32 (adler32-zlib) [ 5237.879371] Key type ._llcrypt registered [ 5237.880923] Key type .llcrypt registered [ 5237.952467] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5238.069251] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 5238.170084] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 5238.172333] LNet: Accept secure, port 988 [ 5239.767124] Key type lgssc registered [ 5240.242530] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5261.854074] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5264.496453] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5272.647720] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5283.324215] Lustre: Mounted lustre-client [ 5286.884178] Lustre: Unmounted lustre-client [ 5305.363124] Key type lgssc unregistered [ 5305.472111] LNet: 185162:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5306.531796] LNet: Removed LNI 192.168.204.58@tcp [ 5306.832734] Key type .llcrypt unregistered [ 5306.834490] Key type ._llcrypt unregistered [ 5312.029428] alg: No test for adler32 (adler32-zlib) [ 5312.785414] Key type ._llcrypt registered [ 5312.787355] Key type .llcrypt registered [ 5312.877458] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5313.014310] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 5313.121928] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 5313.124695] LNet: Accept secure, port 988 [ 5314.727251] Key type lgssc registered [ 5315.218083] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5338.461357] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5340.979694] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5349.160594] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5359.601274] Lustre: Mounted lustre-client [ 5361.350867] Lustre: Unmounted lustre-client [ 5375.784531] Key type lgssc unregistered [ 5375.897608] LNet: 188132:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5376.927888] LNet: Removed LNI 192.168.204.58@tcp [ 5377.211246] Key type .llcrypt unregistered [ 5377.212572] Key type ._llcrypt unregistered [ 5384.057993] alg: No test for adler32 (adler32-zlib) [ 5384.809337] Key type ._llcrypt registered [ 5384.810878] Key type .llcrypt registered [ 5385.371228] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 12:34:33 (1761323673) [ 5395.751305] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5395.896062] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 5395.987602] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 5395.989632] LNet: Accept secure, port 988 [ 5397.584031] Key type lgssc registered [ 5398.025343] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5432.060901] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5434.367509] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5444.040239] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5485.084424] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 12:36:13 (1761323773) [ 5498.816861] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5501.287388] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5509.156270] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5519.851886] Lustre: Mounted lustre-client [ 5522.036176] Lustre: Unmounted lustre-client [ 5540.531898] Key type lgssc unregistered [ 5540.651321] LNet: 195195:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5541.664873] LNet: Removed LNI 192.168.204.58@tcp [ 5541.962811] Key type .llcrypt unregistered [ 5541.964228] Key type ._llcrypt unregistered [ 5547.928703] alg: No test for adler32 (adler32-zlib) [ 5548.679646] Key type ._llcrypt registered [ 5548.681213] Key type .llcrypt registered [ 5549.258979] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 5549.914377] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 12:37:18 (1761323838) [ 5557.385764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5557.516543] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 5557.615381] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 5557.617333] LNet: Accept secure, port 988 [ 5559.207178] Key type lgssc registered [ 5559.690684] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5595.595113] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5597.798222] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5606.156888] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 5613.476591] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 5614.685900] Lustre: Mounted lustre-client [ 5622.052201] Lustre: Unmounted lustre-client [ 5676.081738] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5678.443400] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5687.198263] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5710.736289] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 12:39:59 (1761323999) [ 5711.390486] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 5712.114869] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 12:40:00 (1761324000) [ 5734.731761] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5737.021856] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5759.587763] Key type lgssc unregistered [ 5759.688510] LNet: 206982:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5760.736314] LNet: Removed LNI 192.168.204.58@tcp [ 5760.990463] Key type .llcrypt unregistered [ 5760.991917] Key type ._llcrypt unregistered [ 5766.439742] alg: No test for adler32 (adler32-zlib) [ 5767.191430] Key type ._llcrypt registered [ 5767.193116] Key type .llcrypt registered [ 5767.734338] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 12:40:55 (1761324055) [ 5781.085719] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5781.329734] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 5781.417062] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 5781.419349] LNet: Accept secure, port 988 [ 5783.007110] Key type lgssc registered [ 5783.396990] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5799.091327] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5800.940150] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5807.082883] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5824.672316] Key type lgssc unregistered [ 5824.774339] LNet: 210656:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5824.778182] LNet: Removed LNI 192.168.204.58@tcp [ 5825.065110] Key type .llcrypt unregistered [ 5825.066781] Key type ._llcrypt unregistered [ 5830.794337] alg: No test for adler32 (adler32-zlib) [ 5831.546399] Key type ._llcrypt registered [ 5831.547932] Key type .llcrypt registered [ 5832.074557] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 12:41:59 (1761324119) [ 5833.105350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5833.221724] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 5833.306914] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 5833.309217] LNet: Accept secure, port 988 [ 5834.895133] Key type lgssc registered [ 5835.314710] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5853.913351] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5855.987339] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5862.627971] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5863.793612] Lustre: Mounted lustre-client [ 5874.145875] Lustre: lustre-MDT0000-mdc-ffff9d2884cfd000: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5879.265818] Lustre: lustre-MDT0001-mdc-ffff9d2884cfd000: Connection to lustre-MDT0001 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5886.303095] Lustre: 211626:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761324168/real 1761324168] req@000000004afbc24f x1846882202591360/t0(0) o400->MGC192.168.204.158@tcp@192.168.204.158@tcp:26/25 lens 224/224 e 0 to 1 dl 1761324175 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 5886.314529] LustreError: 166-1: MGC192.168.204.158@tcp: Connection to MGS (at 192.168.204.158@tcp) was lost; in progress operations using this service will fail [ 5892.449871] Lustre: Evicted from MGS (at 192.168.204.158@tcp) after server handle changed from 0x313d33bee789689 to 0x313d33bee78afcd [ 5892.455101] Lustre: MGC192.168.204.158@tcp: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 5901.302152] LustreError: lustre-MDT0001-mdc-ffff9d2884cfd000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 5901.309587] Lustre: lustre-MDT0001-mdc-ffff9d2884cfd000: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 5905.314864] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5911.015878] LustreError: lustre-MDT0000-mdc-ffff9d2884cfd000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5911.031860] Lustre: lustre-MDT0000-mdc-ffff9d2884cfd000: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 5911.994626] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 6 sec [ 5913.970905] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5914.561033] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5915.228353] Lustre: Unmounted lustre-client [ 5949.329480] Key type lgssc unregistered [ 5949.426507] LNet: 215684:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5950.431856] LNet: Removed LNI 192.168.204.58@tcp [ 5950.701018] Key type .llcrypt unregistered [ 5950.702630] Key type ._llcrypt unregistered [ 5955.630717] alg: No test for adler32 (adler32-zlib) [ 5956.382352] Key type ._llcrypt registered [ 5956.383421] Key type .llcrypt registered [ 5956.800036] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 12:44:04 (1761324244) [ 5957.548422] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5957.685576] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 5957.751041] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 5957.752561] LNet: Accept secure, port 988 [ 5959.327121] Key type lgssc registered [ 5959.717871] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5977.085404] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5978.873912] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6008.526543] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6010.380293] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6019.341515] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6025.863301] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6050.590348] Key type lgssc unregistered [ 6050.685253] LNet: 220960:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6050.687406] LNet: Removed LNI 192.168.204.58@tcp [ 6050.883403] Key type .llcrypt unregistered [ 6050.884296] Key type ._llcrypt unregistered [ 6065.954383] alg: No test for adler32 (adler32-zlib) [ 6066.716317] Key type ._llcrypt registered [ 6066.717326] Key type .llcrypt registered [ 6066.789960] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6066.897806] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 6066.987945] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 6066.990669] LNet: Accept secure, port 988 [ 6068.567172] Key type lgssc registered [ 6068.908487] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6097.316383] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6099.134289] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6106.374377] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6123.703691] Lustre: DEBUG MARKER: == conf-sanity test 60: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 12:46:52 (1761324412) [ 6160.325616] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6162.396935] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6169.999310] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6191.515579] Lustre: DEBUG MARKER: == conf-sanity test 61: large xattr ====================== 12:48:00 (1761324480) [ 6202.489066] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6204.521256] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6210.592364] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6211.759137] Lustre: Mounted lustre-client [ 6218.978403] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61.conf-sanity [ 6219.534772] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61.conf-sanity [ 6220.089872] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61.conf-sanity [ 6220.638202] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61.conf-sanity after remounting MDS [ 6222.305971] Lustre: lustre-MDT0000-mdc-ffff9d288d6d6800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6229.471211] Lustre: 221739:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761324511/real 1761324511] req@00000000692f45cf x1846882447921536/t0(0) o400->MGC192.168.204.158@tcp@192.168.204.158@tcp:26/25 lens 224/224 e 0 to 1 dl 1761324518 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 6229.477357] LustreError: 166-1: MGC192.168.204.158@tcp: Connection to MGS (at 192.168.204.158@tcp) was lost; in progress operations using this service will fail [ 6235.618225] Lustre: Evicted from MGS (at 192.168.204.158@tcp) after server handle changed from 0x7edfa14262a1fea1 to 0x7edfa14262a1ffea [ 6235.621869] Lustre: MGC192.168.204.158@tcp: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 6241.600591] Lustre: lustre-MDT0000-mdc-ffff9d288d6d6800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 6243.760723] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6244.458164] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6246.457395] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61.conf-sanity [ 6250.979454] Lustre: lustre-MDT0000-mdc-ffff9d288d6d6800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6258.143078] Lustre: 221736:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761324540/real 1761324540] req@00000000d6530b8c x1846882447924928/t0(0) o400->MGC192.168.204.158@tcp@192.168.204.158@tcp:26/25 lens 224/224 e 0 to 1 dl 1761324547 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 6258.153218] LustreError: 166-1: MGC192.168.204.158@tcp: Connection to MGS (at 192.168.204.158@tcp) was lost; in progress operations using this service will fail [ 6264.291763] Lustre: Evicted from MGS (at 192.168.204.158@tcp) after server handle changed from 0x7edfa14262a1ffea to 0x7edfa14262a202f3 [ 6264.295424] Lustre: MGC192.168.204.158@tcp: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 6270.271592] Lustre: lustre-MDT0000-mdc-ffff9d288d6d6800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 6272.306210] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6272.917433] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6274.522441] Lustre: Unmounted lustre-client [ 6304.311176] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6306.234989] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6312.257187] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6312.405495] Lustre: Mounted lustre-client [ 6317.836189] Lustre: Unmounted lustre-client [ 6335.237376] Key type lgssc unregistered [ 6335.340533] LNet: 236393:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6336.351822] LNet: Removed LNI 192.168.204.58@tcp [ 6336.623886] Key type .llcrypt unregistered [ 6336.624847] Key type ._llcrypt unregistered [ 6342.050316] alg: No test for adler32 (adler32-zlib) [ 6342.802266] Key type ._llcrypt registered [ 6342.803272] Key type .llcrypt registered [ 6343.234407] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 12:50:31 (1761324631) [ 6344.329582] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6344.441449] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 6344.509378] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 6344.511338] LNet: Accept secure, port 988 [ 6346.087125] Key type lgssc registered [ 6346.473115] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6361.307961] Key type lgssc unregistered [ 6361.409406] LNet: 238548:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6361.411918] LNet: Removed LNI 192.168.204.58@tcp [ 6361.646654] Key type .llcrypt unregistered [ 6361.647970] Key type ._llcrypt unregistered [ 6372.148917] alg: No test for adler32 (adler32-zlib) [ 6372.900348] Key type ._llcrypt registered [ 6372.901701] Key type .llcrypt registered [ 6372.972908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6373.073541] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 6373.152687] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 6373.154556] LNet: Accept secure, port 988 [ 6374.735102] Key type lgssc registered [ 6375.059854] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6404.694822] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6406.646058] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6414.445738] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6431.062540] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 6431.592884] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 12:52:00 (1761324720) [ 6441.968587] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6443.772386] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6449.763883] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6457.299782] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6458.464120] Lustre: Mounted lustre-client [ 6463.970386] Lustre: lustre-OST0001-osc-ffff9d288dfda800: Connection to lustre-OST0001 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6466.155588] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6476.413220] Lustre: Unmounted lustre-client [ 6500.364592] Key type lgssc unregistered [ 6500.467455] LNet: 245634:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6501.471966] LNet: Removed LNI 192.168.204.58@tcp [ 6501.713807] Key type .llcrypt unregistered [ 6501.715183] Key type ._llcrypt unregistered [ 6518.700094] alg: No test for adler32 (adler32-zlib) [ 6519.451455] Key type ._llcrypt registered [ 6519.452754] Key type .llcrypt registered [ 6519.521420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6519.625284] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 6519.708530] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 6519.710800] LNet: Accept secure, port 988 [ 6521.295178] Key type lgssc registered [ 6521.727541] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6551.363320] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6553.354513] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6561.335624] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6579.587558] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 12:54:28 (1761324868) [ 6593.811385] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6595.728163] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6607.178722] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 12:54:55 (1761324895) [ 6618.389879] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6620.360568] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6627.014232] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6628.185416] Lustre: Mounted lustre-client [ 6635.429970] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6635.526309] Lustre: Unmounted lustre-client [ 6683.219661] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6685.076196] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6707.487886] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6712.813560] Lustre: Mounted lustre-client [ 6718.346472] Lustre: Unmounted lustre-client [ 6744.485219] Key type lgssc unregistered [ 6744.597445] LNet: 256270:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6745.632149] LNet: Removed LNI 192.168.204.58@tcp [ 6745.891712] Key type .llcrypt unregistered [ 6745.893505] Key type ._llcrypt unregistered [ 6756.835058] alg: No test for adler32 (adler32-zlib) [ 6757.586378] Key type ._llcrypt registered [ 6757.587349] Key type .llcrypt registered [ 6757.668956] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6757.775335] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 6757.851314] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 6757.853040] LNet: Accept secure, port 988 [ 6759.447101] Key type lgssc registered [ 6759.796048] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6789.411933] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6791.406912] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6799.234899] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6820.817939] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 6821.377380] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 12:58:29 (1761325109) [ 6832.172588] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6833.994740] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6840.279773] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6842.346476] Lustre: Mounted lustre-client [ 6848.080157] Lustre: Unmounted lustre-client [ 6865.470677] Key type lgssc unregistered [ 6865.571451] LNet: 262868:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6866.592049] LNet: Removed LNI 192.168.204.58@tcp [ 6866.815845] Key type .llcrypt unregistered [ 6866.817892] Key type ._llcrypt unregistered [ 6872.185777] alg: No test for adler32 (adler32-zlib) [ 6872.936306] Key type ._llcrypt registered [ 6872.937463] Key type .llcrypt registered [ 6873.363781] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 6873.901171] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 12:59:22 (1761325162) [ 6876.950255] Key type .llcrypt unregistered [ 6876.951397] Key type ._llcrypt unregistered [ 6880.791153] alg: No test for adler32 (adler32-zlib) [ 6881.542469] Key type ._llcrypt registered [ 6881.544324] Key type .llcrypt registered [ 6881.623992] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6881.740505] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 6881.834593] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 6881.836277] LNet: Accept secure, port 988 [ 6883.431090] Key type lgssc registered [ 6883.820415] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6902.288656] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6911.980962] Lustre: Mounted lustre-client [ 6912.508125] Lustre: Unmounted lustre-client [ 6929.761053] Key type lgssc unregistered [ 6929.866455] LNet: 266179:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6930.911923] LNet: Removed LNI 192.168.204.58@tcp [ 6931.137039] Key type .llcrypt unregistered [ 6931.137947] Key type ._llcrypt unregistered [ 6936.452757] alg: No test for adler32 (adler32-zlib) [ 6937.203309] Key type ._llcrypt registered [ 6937.204220] Key type .llcrypt registered [ 6937.657856] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 13:00:25 (1761325225) [ 6938.473418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6938.642150] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 6938.724654] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 6938.726541] LNet: Accept secure, port 988 [ 6940.319137] Key type lgssc registered [ 6940.710729] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7032.796825] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7058.720823] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7060.627256] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7061.775210] Lustre: Mounted lustre-client [ 7062.313463] Lustre: Unmounted lustre-client [ 7079.709703] Key type lgssc unregistered [ 7079.819519] LNet: 269692:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7080.864394] LNet: Removed LNI 192.168.204.58@tcp [ 7081.149435] Key type .llcrypt unregistered [ 7081.150658] Key type ._llcrypt unregistered [ 7086.630788] alg: No test for adler32 (adler32-zlib) [ 7087.382312] Key type ._llcrypt registered [ 7087.383287] Key type .llcrypt registered [ 7087.895574] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 13:02:55 (1761325375) [ 7088.565997] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7088.782900] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 7088.861431] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7088.863198] LNet: Accept secure, port 988 [ 7090.439148] Key type lgssc registered [ 7090.792887] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7109.376596] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7111.197809] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7117.794551] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7118.968817] Lustre: Mounted lustre-client [ 7124.451376] Lustre: lustre-MDT0000-mdc-ffff9d28874d9000: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7126.468860] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7126.481594] LustreError: 272802:0:(file.c:5205:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 7126.608157] LustreError: 166-1: MGC192.168.204.158@tcp: Connection to MGS (at 192.168.204.158@tcp) was lost; in progress operations using this service will fail [ 7126.631222] Lustre: Unmounted lustre-client [ 7137.597380] Key type lgssc unregistered [ 7137.714347] LNet: 273217:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7138.719489] LNet: Removed LNI 192.168.204.58@tcp [ 7138.992962] Key type .llcrypt unregistered [ 7138.994550] Key type ._llcrypt unregistered [ 7144.533950] alg: No test for adler32 (adler32-zlib) [ 7145.285453] Key type ._llcrypt registered [ 7145.286986] Key type .llcrypt registered [ 7145.771869] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 13:03:53 (1761325433) [ 7146.625708] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7146.728538] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 7146.802918] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7146.804445] LNet: Accept secure, port 988 [ 7148.391145] Key type lgssc registered [ 7148.768162] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7166.785530] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7168.659587] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7174.945513] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7176.113660] Lustre: Mounted lustre-client [ 7192.051325] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7192.410746] Lustre: Unmounted lustre-client [ 7209.300940] Key type lgssc unregistered [ 7209.419739] LNet: 276752:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7210.465223] LNet: Removed LNI 192.168.204.58@tcp [ 7210.773654] Key type .llcrypt unregistered [ 7210.776058] Key type ._llcrypt unregistered [ 7216.335706] alg: No test for adler32 (adler32-zlib) [ 7217.087495] Key type ._llcrypt registered [ 7217.088538] Key type .llcrypt registered [ 7217.558867] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 13:05:05 (1761325505) [ 7224.566889] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7224.721455] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 7224.799101] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7224.801517] LNet: Accept secure, port 988 [ 7226.383113] Key type lgssc registered [ 7226.792156] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7263.245489] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7264.412496] Lustre: Mounted lustre-client [ 7280.098606] Lustre: lustre-MDT0001-mdc-ffff9d288d473800: Connection to lustre-MDT0001 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7284.895422] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7295.161220] Lustre: Unmounted lustre-client [ 7318.390783] Key type lgssc unregistered [ 7318.499630] LNet: 282194:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7319.520450] LNet: Removed LNI 192.168.204.58@tcp [ 7319.775172] Key type .llcrypt unregistered [ 7319.777055] Key type ._llcrypt unregistered [ 7325.380575] alg: No test for adler32 (adler32-zlib) [ 7326.131292] Key type ._llcrypt registered [ 7326.132229] Key type .llcrypt registered [ 7326.632801] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 13:06:54 (1761325614) [ 7327.229054] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 7327.837272] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 13:06:56 (1761325616) [ 7328.451323] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 7329.060217] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 13:06:57 (1761325617) [ 7329.566987] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 7330.112542] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 13:06:58 (1761325618) [ 7330.634953] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 7331.159843] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 13:06:59 (1761325619) [ 7331.675707] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 7332.247049] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 13:07:00 (1761325620) [ 7335.251326] Key type .llcrypt unregistered [ 7335.252369] Key type ._llcrypt unregistered [ 7338.355662] alg: No test for adler32 (adler32-zlib) [ 7339.106423] Key type ._llcrypt registered [ 7339.107373] Key type .llcrypt registered [ 7339.181197] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7339.297304] Lustre: Lustre: Build Version: 2.15.7_9_gefaba72 [ 7339.388259] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7339.389778] LNet: Accept secure, port 988 [ 7340.975141] Key type lgssc registered [ 7341.391609] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7368.097289] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7369.891194] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7377.200774] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7378.359347] Lustre: Mounted lustre-client [ 7378.848538] Lustre: Unmounted lustre-client [ 7398.186755] Lustre: DEBUG MARKER: == conf-sanity test 73: failnode to update from mountdata properly ========================================================== 13:08:06 (1761325686) [ 7408.974805] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7410.864611] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7416.958783] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7417.123095] Lustre: Mounted lustre-client [ 7417.491184] Lustre: Unmounted lustre-client [ 7440.435163] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 13:08:48 (1761325728) [ 7477.318172] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7479.392689] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7486.783612] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7527.726915] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7310 sec ========== 13:10:16 (1761325816) [ 7538.734099] Key type lgssc unregistered [ 7538.846535] LNet: 297945:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7546.015972] LNet: Removed LNI 192.168.204.58@tcp [ 7546.267609] Key type .llcrypt unregistered [ 7546.268621] Key type ._llcrypt unregistered