[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 436732027 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001023] APIC: Switch to symmetric I/O mode setup [ 0.003337] x2apic enabled [ 0.004009] Switched APIC routing to physical x2apic. [ 0.005016] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009010] pid_max: default: 32768 minimum: 301 [ 0.010104] LSM: Security Framework initializing [ 0.011036] Yama: becoming mindful. [ 0.012023] SELinux: Initializing. [ 0.013045] *** VALIDATE selinux *** [ 0.019576] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024517] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025165] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026116] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027116] *** VALIDATE tmpfs *** [ 0.028484] *** VALIDATE proc *** [ 0.029263] *** VALIDATE cgroup *** [ 0.030011] *** VALIDATE cgroup2 *** [ 0.031293] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032167] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034031] Spectre V2 : User space: Vulnerable [ 0.035008] Speculative Store Bypass: Vulnerable [ 0.038407] debug: unmapping init [mem 0xffffffff88059000-0xffffffff88060fff] [ 0.040172] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.041756] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.042032] ... version: 2 [ 0.043014] ... bit width: 48 [ 0.044012] ... generic registers: 4 [ 0.045011] ... value mask: 0000ffffffffffff [ 0.046012] ... max period: 00007fffffffffff [ 0.047016] ... fixed-purpose events: 3 [ 0.048013] ... event mask: 000000070000000f [ 0.049306] rcu: Hierarchical SRCU implementation. [ 0.051539] smp: Bringing up secondary CPUs ... [ 0.052640] x86: Booting SMP configuration: [ 0.053027] .... node #0, CPUs: #1 #2 #3 [ 0.056509] smp: Brought up 1 node, 4 CPUs [ 0.058013] smpboot: Max logical packages: 1 [ 0.059021] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.143023] node 0 deferred pages initialised in 82ms [ 0.146139] devtmpfs: initialized [ 0.147292] x86/mm: Memory block size: 128MB [ 0.150872] gcov: version magic: 0x41383552 [ 0.154125] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.157117] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.160313] pinctrl core: initialized pinctrl subsystem [ 0.162199] [ 0.162760] ************************************************************* [ 0.165015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.169014] ** ** [ 0.172013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.174012] ** ** [ 0.176014] ** This means that this kernel is built to expose internal ** [ 0.179013] ** IOMMU data structures, which may compromise security on ** [ 0.183015] ** your system. ** [ 0.186012] ** ** [ 0.188012] ** If you see this message and you are not debugging the ** [ 0.190013] ** kernel, report this immediately to your vendor! ** [ 0.193011] ** ** [ 0.195011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.197014] ************************************************************* [ 0.200632] NET: Registered protocol family 16 [ 0.202413] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.204063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.207068] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.210429] cpuidle: using governor menu [ 0.211851] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.214509] PCI: Using configuration type 1 for base access [ 0.216126] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.226070] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.229030] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.233123] cryptd: max_cpu_qlen set to 1000 [ 0.236185] ACPI: Added _OSI(Module Device) [ 0.238018] ACPI: Added _OSI(Processor Device) [ 0.240014] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.241014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.245996] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.250500] ACPI: Interpreter enabled [ 0.252068] ACPI: PM: (supports S0 S3 S4 S5) [ 0.254015] ACPI: Using IOAPIC for interrupt routing [ 0.256140] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.259403] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.268000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.271044] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.273018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.276076] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.281323] acpiphp: Slot [2] registered [ 0.282164] acpiphp: Slot [5] registered [ 0.284133] acpiphp: Slot [6] registered [ 0.285121] acpiphp: Slot [3] registered [ 0.287078] acpiphp: Slot [4] registered [ 0.288101] acpiphp: Slot [7] registered [ 0.290106] acpiphp: Slot [8] registered [ 0.292122] acpiphp: Slot [9] registered [ 0.293120] acpiphp: Slot [10] registered [ 0.295123] acpiphp: Slot [11] registered [ 0.296104] acpiphp: Slot [12] registered [ 0.298109] acpiphp: Slot [13] registered [ 0.299179] acpiphp: Slot [14] registered [ 0.301104] acpiphp: Slot [15] registered [ 0.302132] acpiphp: Slot [16] registered [ 0.304111] acpiphp: Slot [17] registered [ 0.305102] acpiphp: Slot [18] registered [ 0.307096] acpiphp: Slot [19] registered [ 0.308106] acpiphp: Slot [20] registered [ 0.310112] acpiphp: Slot [21] registered [ 0.312120] acpiphp: Slot [22] registered [ 0.313125] acpiphp: Slot [23] registered [ 0.315129] acpiphp: Slot [24] registered [ 0.317118] acpiphp: Slot [25] registered [ 0.318110] acpiphp: Slot [26] registered [ 0.320115] acpiphp: Slot [27] registered [ 0.321146] acpiphp: Slot [28] registered [ 0.323107] acpiphp: Slot [29] registered [ 0.325109] acpiphp: Slot [30] registered [ 0.326210] acpiphp: Slot [31] registered [ 0.328067] PCI host bridge to bus 0000:00 [ 0.330021] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.332025] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.335029] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.337024] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.340029] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.343041] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.345206] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.348093] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.351149] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.357481] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.361065] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.364020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.368023] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.370027] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.373784] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.377487] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.380052] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.382887] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.388017] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.398074] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.403012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.408141] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.413016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.420018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.433017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.443322] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.448017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.452014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.464017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.471015] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.473362] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.475334] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.477352] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.479200] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.484128] iommu: Default domain type: Passthrough [ 0.485432] SCSI subsystem initialized [ 0.487145] ACPI: bus type USB registered [ 0.489095] usbcore: registered new interface driver usbfs [ 0.491092] usbcore: registered new interface driver hub [ 0.493081] usbcore: registered new device driver usb [ 0.495130] pps_core: LinuxPPS API ver. 1 registered [ 0.496009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.500060] PTP clock support registered [ 0.501133] EDAC MC: Ver: 3.0.0 [ 0.503134] PCI: Using ACPI for IRQ routing [ 0.504700] NetLabel: Initializing [ 0.506008] NetLabel: domain hash size = 128 [ 0.507007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.510079] NetLabel: unlabeled traffic allowed by default [ 0.511108] vgaarb: loaded [ 0.512277] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.514014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.521428] clocksource: Switched to clocksource kvm-clock [ 0.631867] VFS: Disk quotas dquot_6.6.0 [ 0.633674] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.636627] *** VALIDATE ramfs *** [ 0.638053] *** VALIDATE hugetlbfs *** [ 0.639586] pnp: PnP ACPI init [ 0.642199] pnp: PnP ACPI: found 6 devices [ 0.658347] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.662167] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.664782] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.667302] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.670220] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.672779] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.675922] NET: Registered protocol family 2 [ 0.677713] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.682442] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.686154] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.692686] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.696937] TCP: Hash tables configured (established 65536 bind 65536) [ 0.700716] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.704466] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.707600] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.710962] NET: Registered protocol family 1 [ 0.713877] RPC: Registered named UNIX socket transport module. [ 0.715805] RPC: Registered udp transport module. [ 0.717839] RPC: Registered tcp transport module. [ 0.719534] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.721996] NET: Registered protocol family 44 [ 0.723810] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.725928] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.727555] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.729139] PCI: CLS 0 bytes, default 64 [ 0.730222] Unpacking initramfs... [ 2.144421] debug: unmapping init [mem 0xffff8fc4fcc64000-0xffff8fc4fffcffff] [ 2.150020] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.152314] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.156352] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.641767] Initialise system trusted keyrings [ 2.643039] Key type blacklist registered [ 2.644274] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.650659] zbud: loaded [ 2.652949] *** VALIDATE nfs *** [ 2.654027] *** VALIDATE nfs4 *** [ 2.655618] pstore: using deflate compression [ 2.658657] Platform Keyring initialized [ 2.737635] NET: Registered protocol family 38 [ 2.738826] Key type asymmetric registered [ 2.739689] Asymmetric key parser 'x509' registered [ 2.741082] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.743364] io scheduler mq-deadline registered [ 2.744641] io scheduler kyber registered [ 2.746030] io scheduler bfq registered [ 2.747497] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.749886] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.752594] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.754948] ACPI: Power Button [PWRF] [ 2.759860] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.765700] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.773581] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.801512] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.828051] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.832202] Non-volatile memory driver v1.3 [ 2.833750] Linux agpgart interface v0.103 [ 2.859508] virtio_blk virtio1: [vda] 67992 512-byte logical blocks (34.8 MB/33.2 MiB) [ 2.861399] vda: detected capacity change from 0 to 34811904 [ 2.877097] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.881412] vdb: detected capacity change from 0 to 1073741824 [ 2.892703] libphy: Fixed MDIO Bus: probed [ 2.899571] usbcore: registered new interface driver usbserial_generic [ 2.902721] usbserial: USB Serial support registered for generic [ 2.904914] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.909327] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.910804] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.913533] mousedev: PS/2 mouse device common for all mice [ 2.916710] rtc_cmos 00:05: RTC can wake from S4 [ 2.919877] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.920231] rtc_cmos 00:05: registered as rtc0 [ 2.924689] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.925245] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.927022] intel_pstate: CPU model not supported [ 2.931632] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.936236] hid: raw HID events driver (C) Jiri Kosina [ 2.938410] usbcore: registered new interface driver usbhid [ 2.940245] usbhid: USB HID core driver [ 2.941384] drop_monitor: Initializing network drop monitor service [ 2.942801] Initializing XFRM netlink socket [ 2.944238] NET: Registered protocol family 10 [ 2.946639] Segment Routing with IPv6 [ 2.948139] NET: Registered protocol family 17 [ 2.949923] mpls_gso: MPLS GSO support [ 2.955378] RAS: Correctable Errors collector initialized. [ 2.956942] AVX version of gcm_enc/dec engaged. [ 2.958045] AES CTR mode by8 optimization enabled [ 3.018298] sched_clock: Marking stable (3018270408, 0)->(3889885312, -871614904) [ 3.020966] registered taskstats version 1 [ 3.022380] Loading compiled-in X.509 certificates [ 3.024031] zswap: loaded using pool lzo/zbud [ 3.043867] Key type big_key registered [ 3.053429] Key type encrypted registered [ 3.054720] ima: No TPM chip found, activating TPM-bypass! [ 3.056054] ima: Allocated hash algorithm: sha1 [ 3.057182] ima: No architecture policies found [ 3.058456] evm: Initialising EVM extended attributes: [ 3.059802] evm: security.selinux [ 3.060712] evm: security.ima [ 3.061815] evm: security.capability [ 3.063276] evm: HMAC attrs: 0x1 [ 3.065543] rtc_cmos 00:05: setting system clock to 2026-04-14 20:44:28 UTC (1776199468) [ 3.070876] debug: unmapping init [mem 0xffffffff89003000-0xffffffff891fffff] [ 3.073171] debug: unmapping init [mem 0xffffffff87d82000-0xffffffff88058fff] [ 3.081065] Write protecting the kernel read-only data: 28672k [ 3.083866] debug: unmapping init [mem 0xffffffff86403000-0xffffffff865fffff] [ 3.086022] debug: unmapping init [mem 0xffffffff86d14000-0xffffffff86dfffff] [ 3.114067] 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.120578] systemd[1]: Detected virtualization kvm. [ 3.122612] systemd[1]: Detected architecture x86-64. [ 3.124045] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.148930] systemd[1]: No hostname configured. [ 3.150502] systemd[1]: Set hostname to . [ 3.152063] random: systemd: uninitialized urandom read (16 bytes read) [ 3.154011] systemd[1]: Initializing machine ID from random generator. [ 3.185969] random: ln: uninitialized urandom read (6 bytes read) [ 3.289989] random: systemd: uninitialized urandom read (16 bytes read) [ 3.292516] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.297170] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.301935] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local File Systems. [ OK ] Reached target Slices. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... [ OK ] Reached target Paths. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. Starting Create Volatile Files and Directories... Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.932401] device-mapper: uevent: version 1.0.3 [ 3.934874] 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. [ 4.603789] random: fast init done [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.642715] virtio_net virtio0 ens2: renamed from eth0 [ 4.710714] scsi host0: ata_piix [ 4.739290] scsi host1: ata_piix [ 4.740609] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.742694] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.078724] dracut-initqueue[583]: RTNETLINK answers: File exists [ 9.568468] random: crng init done [ 9.569362] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 9.914046] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.111823] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.374150] SELinux: Disabled at runtime. [ 11.432247] 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.441569] systemd[1]: Detected virtualization kvm. [ 11.443491] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.956436] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.960066] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.965340] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.971382] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.974063] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.981318] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.006899] systemd[1]: Listening on Process Core Dump Socket. [ OK ] Listening on Process Core Dump Socket. Mounting Kernel Debug File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-getty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on[ 12.068622] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS udev Kernel Socket. Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Apply Kernel Variables... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting Huge Pages File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.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 ] Reached target Paths. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ 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 ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 12.512564] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.826433] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.869180] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.970126] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.979964] EDAC sbridge: Ver: 1.1.2 [ 14.198298] Key type dns_resolver registered [ 14.499712] NFS: Registering the id_resolver key type [ 14.501937] Key type id_resolver registered [ 14.503692] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ 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 Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg219-client login: [ 54.107967] libcfs: loading out-of-tree module taints kernel. [ 54.135144] alg: No test for adler32 (adler32-zlib) [ 54.896392] Key type ._llcrypt registered [ 54.897863] Key type .llcrypt registered [ 55.186166] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 55.698507] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 56.306975] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 56.309247] LNet: Accept secure, port 988 [ 58.055225] Key type lgssc registered [ 58.747058] hrtimer: interrupt took 5344119 ns [ 59.980996] Lustre: Echo OBD driver; http://www.lustre.org/ [ 193.072752] Lustre: Mounted lustre-client [ 198.362506] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 212.795625] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing check_logdir /tmp/testlogs/ [ 217.710559] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing yml_node [ 218.592162] Lustre: lustre-OST0000-osc-ffff8fc547f52800: disconnect after 23s idle [ 222.558562] Lustre: DEBUG MARKER: Client: 2.15.8.2 [ 225.025954] Lustre: DEBUG MARKER: MDS: 2.15.8.2 [ 227.375917] Lustre: DEBUG MARKER: OSS: 2.15.8.2 [ 229.346976] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Apr 14 16:48:13 EDT 2026 [ 237.432804] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball 110 [ 238.966119] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 [ 241.445061] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 251.825528] Lustre: Unmounted lustre-client [ 364.743702] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 369.890340] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 388.922983] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 427.674082] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 16:51:30 (1776199890) [ 430.151371] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 431.429558] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 16:51:35 (1776199895) [ 466.580616] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 472.586923] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 495.311637] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 496.135188] Lustre: Mounted lustre-client [ 501.515824] Lustre: Unmounted lustre-client [ 529.259399] Key type lgssc unregistered [ 529.555907] LNet: 14352:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 530.592673] LNet: Removed LNI 192.168.202.19@tcp [ 531.263546] Key type .llcrypt unregistered [ 531.265967] Key type ._llcrypt unregistered [ 543.815754] alg: No test for adler32 (adler32-zlib) [ 544.569292] Key type ._llcrypt registered [ 544.571520] Key type .llcrypt registered [ 545.440297] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 16:53:29 (1776200009) [ 547.354276] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 547.624197] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 547.838288] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 547.842605] LNet: Accept secure, port 988 [ 549.503205] Key type lgssc registered [ 550.579606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 591.210856] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 596.850607] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 618.245461] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 624.152287] Lustre: Mounted lustre-client [ 629.648950] Lustre: Unmounted lustre-client [ 656.594357] Key type lgssc unregistered [ 656.816689] LNet: 17933:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 657.824931] LNet: Removed LNI 192.168.202.19@tcp [ 658.563646] Key type .llcrypt unregistered [ 658.566942] Key type ._llcrypt unregistered [ 671.718178] alg: No test for adler32 (adler32-zlib) [ 672.475509] Key type ._llcrypt registered [ 672.477284] Key type .llcrypt registered [ 673.649643] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 16:55:36 (1776200136) [ 675.761390] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 676.081603] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 676.253087] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 676.259651] LNet: Accept secure, port 988 [ 677.903231] Key type lgssc registered [ 678.951611] Lustre: Echo OBD driver; http://www.lustre.org/ [ 722.212699] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 727.201211] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 744.555533] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 750.631661] Lustre: Mounted lustre-client [ 755.691734] Lustre: lustre-MDT0000-mdc-ffff8fc547cdd800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 760.804147] Lustre: lustre-MDT0001-mdc-ffff8fc547cdd800: Connection to lustre-MDT0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 762.995677] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 767.968783] Lustre: 18876:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776200226/real 1776200226] req@000000002efe0aa6 x1862480839052032/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1776200233 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 767.992838] LustreError: 166-1: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [ 773.376305] Lustre: Unmounted lustre-client [ 791.170168] Key type lgssc unregistered [ 791.355250] LNet: 21556:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 792.415828] LNet: Removed LNI 192.168.202.19@tcp [ 792.898328] Key type .llcrypt unregistered [ 792.900632] Key type ._llcrypt unregistered [ 806.178471] alg: No test for adler32 (adler32-zlib) [ 806.930554] Key type ._llcrypt registered [ 806.933504] Key type .llcrypt registered [ 808.298457] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 16:57:51 (1776200271) [ 810.416505] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 810.772342] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 810.968852] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 810.976684] LNet: Accept secure, port 988 [ 812.639355] Key type lgssc registered [ 814.080910] Lustre: Echo OBD driver; http://www.lustre.org/ [ 862.177133] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 867.161740] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 885.220413] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 890.909473] Lustre: Mounted lustre-client [ 895.979443] Lustre: lustre-OST0000-osc-ffff8fc547c92000: Connection to lustre-OST0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 902.933445] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 913.236877] Lustre: Unmounted lustre-client [ 940.593056] Key type lgssc unregistered [ 940.944284] LNet: 25137:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 941.985201] LNet: Removed LNI 192.168.202.19@tcp [ 942.762567] Key type .llcrypt unregistered [ 942.765859] Key type ._llcrypt unregistered [ 957.897240] alg: No test for adler32 (adler32-zlib) [ 958.649644] Key type ._llcrypt registered [ 958.651917] Key type .llcrypt registered [ 960.006741] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 17:00:23 (1776200423) [ 962.650620] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 963.132216] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 963.399697] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 963.406427] LNet: Accept secure, port 988 [ 965.144068] Key type lgssc registered [ 966.371629] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1022.758395] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1027.610618] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1046.589584] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1048.318960] Lustre: Mounted lustre-client [ 1051.675574] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1055.202897] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1056.781896] LNetError: 120-3: Refusing connection from 192.168.202.19 for 127.0.0.2@tcp: No matching NI [ 1056.799205] LNetError: 26065:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 1056.812588] 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? [ 1066.893801] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-04-1417h02m10s [ 1068.909799] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1074.153369] Lustre: lustre-MDT0000-mdc-ffff8fc5463d0000: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1074.201953] LNetError: 120-3: Refusing connection from 192.168.202.19 for 127.0.0.2@tcp: No matching NI [ 1074.223666] LNetError: 26066:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 1074.245977] 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? [ 1079.110841] LustreError: 28903:0:(lmv_obd.c:1273:lmv_statfs()) lustre-MDT0000-mdc-ffff8fc5463d0000: can't stat MDS #0: rc = -110 [ 1079.272025] LNetError: 120-3: Refusing connection from 192.168.202.19 for 127.0.0.2@tcp: No matching NI [ 1079.298237] LNetError: 26063:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 1079.307493] 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? [ 1080.384149] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1084.398968] LNetError: 120-3: Refusing connection from 192.168.202.19 for 127.0.0.2@tcp: No matching NI [ 1084.411831] LNetError: 26064:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 1084.418977] 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? [ 1086.431234] Lustre: 26076:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776200544/real 1776200544] req@000000009cb43416 x1862481139994304/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1776200551 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 1086.455743] LustreError: 166-1: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [ 1086.474916] Lustre: Evicted from MGS (at 192.168.202.119@tcp) after server handle changed from 0x422e0fe2ca82c0e6 to 0x422e0fe2ca82c205 [ 1086.503601] Lustre: MGC192.168.202.119@tcp: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 1091.577194] LustreError: lustre-MDT0000-mdc-ffff8fc5463d0000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1091.605655] Lustre: lustre-MDT0000-mdc-ffff8fc5463d0000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 1098.216921] Lustre: DEBUG MARKER: Wait for df (28903) ... [ 1099.972680] Lustre: DEBUG MARKER: done [ 1102.160777] Lustre: Unmounted lustre-client [ 1129.834353] Key type lgssc unregistered [ 1130.058824] LNet: 29912:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1131.104687] LNet: Removed LNI 192.168.202.19@tcp [ 1132.096749] Key type .llcrypt unregistered [ 1132.107875] Key type ._llcrypt unregistered [ 1183.183082] alg: No test for adler32 (adler32-zlib) [ 1183.999265] Key type ._llcrypt registered [ 1184.004361] Key type .llcrypt registered [ 1184.254889] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1184.577307] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 1184.756916] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 1184.760365] LNet: Accept secure, port 988 [ 1186.423122] Key type lgssc registered [ 1187.413595] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1265.043313] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1270.510657] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1292.460812] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1331.642879] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 17:06:35 (1776200795) [ 1362.404814] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1367.445314] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1388.051770] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1389.889088] Lustre: Mounted lustre-client [ 1395.111815] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-04-1417h07m38s [ 1396.796465] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1404.352509] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1405.463509] LNetError: 120-3: Refusing connection from 192.168.202.19 for 127.0.0.2@tcp: No matching NI [ 1405.480058] LNetError: 30684:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 1405.492025] 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? [ 1407.231456] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1427.935192] Lustre: 36953:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776200872/real 1776200872] req@0000000000a167f7 x1862481372777728/t0(0) o101->lustre-MDT0000-mdc-ffff8fc545875000@192.168.202.119@tcp:12/10 lens 576/1152 e 0 to 1 dl 1776200892 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'' [ 1427.978106] Lustre: lustre-MDT0000-mdc-ffff8fc545875000: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1428.035395] Lustre: lustre-MDT0000-mdc-ffff8fc545875000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 1449.851086] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1454.101286] Lustre: DEBUG MARKER: done [ 1460.820097] Lustre: Unmounted lustre-client [ 1487.327165] Key type lgssc unregistered [ 1487.546687] LNet: 37833:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1488.609047] LNet: Removed LNI 192.168.202.19@tcp [ 1489.185614] Key type .llcrypt unregistered [ 1489.187688] Key type ._llcrypt unregistered [ 1529.179365] alg: No test for adler32 (adler32-zlib) [ 1529.936159] Key type ._llcrypt registered [ 1529.939500] Key type .llcrypt registered [ 1530.221301] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1530.583196] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 1530.915216] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 1530.921346] LNet: Accept secure, port 988 [ 1532.633840] Key type lgssc registered [ 1533.794607] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1616.056521] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1622.188995] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1643.030605] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1683.875181] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 17:12:27 (1776201147) [ 1685.934838] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1687.753754] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 17:12:31 (1776201151) [ 1716.589837] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 17:13:00 (1776201180) [ 1744.463223] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1749.220563] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1766.089649] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1777.183312] Lustre: Mounted lustre-client [ 1780.811033] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1784.688764] Lustre: Unmounted lustre-client [ 1799.239438] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1842.182257] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1847.808680] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1848.490721] Lustre: Mounted lustre-client [ 1851.517404] Lustre: Unmounted lustre-client [ 1914.707697] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1919.930992] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1920.508707] Lustre: Mounted lustre-client [ 1924.588396] Lustre: Unmounted lustre-client [ 1946.950527] Lustre: DEBUG MARKER: files compared the same [ 1959.356955] Key type lgssc unregistered [ 1959.610250] LNet: 49061:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1960.673475] LNet: Removed LNI 192.168.202.19@tcp [ 1961.340658] Key type .llcrypt unregistered [ 1961.343950] Key type ._llcrypt unregistered [ 1975.457502] alg: No test for adler32 (adler32-zlib) [ 1976.215043] Key type ._llcrypt registered [ 1976.224219] Key type .llcrypt registered [ 1977.679875] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 17:17:20 (1776201440) [ 1979.881350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1980.333991] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 1980.520845] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 1980.523471] LNet: Accept secure, port 988 [ 1982.184132] Key type lgssc registered [ 1983.473409] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2032.940492] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2037.873688] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2056.881801] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2063.400966] Lustre: Mounted lustre-client [ 2069.818905] Lustre: Unmounted lustre-client [ 2098.551379] Key type lgssc unregistered [ 2098.774874] LNet: 52615:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2099.808348] LNet: Removed LNI 192.168.202.19@tcp [ 2100.463533] Key type .llcrypt unregistered [ 2100.465896] Key type ._llcrypt unregistered [ 2118.593957] alg: No test for adler32 (adler32-zlib) [ 2119.357548] Key type ._llcrypt registered [ 2119.366462] Key type .llcrypt registered [ 2121.051741] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 17:19:43 (1776201583) [ 2123.798112] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2124.442957] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 2124.717533] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 2124.721787] LNet: Accept secure, port 988 [ 2126.423085] Key type lgssc registered [ 2127.661081] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2239.246505] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2274.136908] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2279.195295] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2305.092788] Key type lgssc unregistered [ 2305.320891] LNet: 56068:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2305.337814] LNet: Removed LNI 192.168.202.19@tcp [ 2305.903103] Key type .llcrypt unregistered [ 2305.904907] Key type ._llcrypt unregistered [ 2320.536654] alg: No test for adler32 (adler32-zlib) [ 2321.301719] Key type ._llcrypt registered [ 2321.303641] Key type .llcrypt registered [ 2322.446774] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 17:23:05 (1776201785) [ 2325.856904] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2326.221172] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 2326.468217] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 2326.472373] LNet: Accept secure, port 988 [ 2328.127292] Key type lgssc registered [ 2328.956300] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2403.146289] Lustre: Mounted lustre-client [ 2410.096505] Lustre: Unmounted lustre-client [ 2455.176262] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 17:25:19 (1776201919) [ 2581.732523] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2606.608921] Lustre: Mounted lustre-client [ 2613.759821] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2613.776831] LustreError: 62172:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8fc5482ef000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2613.819766] Lustre: 57032:0:(llite_lib.c:3595:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.119@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2624.178701] Lustre: Unmounted lustre-client [ 2685.367843] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 17:29:08 (1776202148) [ 2697.189901] Key type lgssc unregistered [ 2697.490758] LNet: 63392:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2698.528885] LNet: Removed LNI 192.168.202.19@tcp [ 2699.302612] Key type .llcrypt unregistered [ 2699.310692] Key type ._llcrypt unregistered [ 2710.544189] alg: No test for adler32 (adler32-zlib) [ 2711.314366] Key type ._llcrypt registered [ 2711.317658] Key type .llcrypt registered [ 2711.514211] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2711.791182] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 2711.981135] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 2711.988695] LNet: Accept secure, port 988 [ 2713.655134] Key type lgssc registered [ 2714.856914] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2767.661465] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2772.488985] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2788.921350] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2790.526565] Lustre: Mounted lustre-client [ 2794.321631] Lustre: Unmounted lustre-client [ 2824.462903] Key type lgssc unregistered [ 2824.721231] LNet: 66368:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2825.776262] LNet: Removed LNI 192.168.202.19@tcp [ 2826.620980] Key type .llcrypt unregistered [ 2826.622206] Key type ._llcrypt unregistered [ 2836.563426] alg: No test for adler32 (adler32-zlib) [ 2837.321629] Key type ._llcrypt registered [ 2837.324822] Key type .llcrypt registered [ 2837.559835] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2837.827083] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 2838.075561] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 2838.080932] LNet: Accept secure, port 988 [ 2839.775743] Key type lgssc registered [ 2840.868993] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2945.994394] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2952.848866] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2972.687605] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2974.240732] Lustre: Mounted lustre-client [ 2977.076165] Lustre: Unmounted lustre-client [ 3002.588995] Key type lgssc unregistered [ 3002.772615] LNet: 69754:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3003.809513] LNet: Removed LNI 192.168.202.19@tcp [ 3004.494505] Key type .llcrypt unregistered [ 3004.496024] Key type ._llcrypt unregistered [ 3022.272218] alg: No test for adler32 (adler32-zlib) [ 3023.027746] Key type ._llcrypt registered [ 3023.036417] Key type .llcrypt registered [ 3024.432664] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 17:34:47 (1776202487) [ 3027.063846] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3027.445871] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 3027.741866] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 3027.748432] LNet: Accept secure, port 988 [ 3029.401132] Key type lgssc registered [ 3030.609725] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3074.652178] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3079.975215] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3097.658602] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3103.779839] Lustre: Mounted lustre-client [ 3109.321907] Lustre: Unmounted lustre-client [ 3109.740686] Lustre: Mounted lustre-client [ 3112.902985] Lustre: Unmounted lustre-client [ 3144.909795] Key type lgssc unregistered [ 3145.297348] LNet: 73413:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3146.342340] LNet: Removed LNI 192.168.202.19@tcp [ 3147.761515] Key type .llcrypt unregistered [ 3147.767939] Key type ._llcrypt unregistered [ 3161.951489] alg: No test for adler32 (adler32-zlib) [ 3162.707434] Key type ._llcrypt registered [ 3162.710195] Key type .llcrypt registered [ 3162.889986] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3163.156892] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 3163.398923] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 3163.401230] LNet: Accept secure, port 988 [ 3165.055166] Key type lgssc registered [ 3166.141599] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3215.665067] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3221.195494] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3241.442697] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3243.303812] Lustre: Mounted lustre-client [ 3247.827667] Lustre: Unmounted lustre-client [ 3277.202855] Key type lgssc unregistered [ 3277.523863] LNet: 76416:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3278.564065] LNet: Removed LNI 192.168.202.19@tcp [ 3279.330330] Key type .llcrypt unregistered [ 3279.333700] Key type ._llcrypt unregistered [ 3292.405214] alg: No test for adler32 (adler32-zlib) [ 3293.157529] Key type ._llcrypt registered [ 3293.159752] Key type .llcrypt registered [ 3294.058873] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 17:39:17 (1776202757) [ 3295.773647] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3295.936431] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 3296.095887] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 3296.099824] LNet: Accept secure, port 988 [ 3297.737653] Key type lgssc registered [ 3298.874747] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3351.386750] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3359.037984] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3377.608487] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3379.070420] Lustre: Mounted lustre-client [ 3387.892983] Lustre: lustre: root_squash is set to 0:0 [ 3397.174762] Lustre: lustre: nosquash_nids is cleared [ 3397.199744] Lustre: lustre: root_squash is set to 500:500 [ 3407.446097] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.19@tcp 192.168.0.[2,10]@tcp [ 3410.662589] Lustre: Unmounted lustre-client [ 3433.380696] Key type lgssc unregistered [ 3433.671342] LNet: 80234:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3434.720498] LNet: Removed LNI 192.168.202.19@tcp [ 3435.358529] Key type .llcrypt unregistered [ 3435.361256] Key type ._llcrypt unregistered [ 3451.146977] alg: No test for adler32 (adler32-zlib) [ 3451.901672] Key type ._llcrypt registered [ 3451.906123] Key type .llcrypt registered [ 3453.404487] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 17:41:56 (1776202916) [ 3456.512582] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3458.441735] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 17:42:02 (1776202922) [ 3461.431479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3461.796799] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 3462.250905] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 3462.264075] LNet: Accept secure, port 988 [ 3463.935601] Key type lgssc registered [ 3465.566171] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3516.726560] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3522.436046] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3539.270900] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3539.821498] Lustre: lustre: root_squash is set to 500:500 [ 3539.846647] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.19@tcp 192.168.0.[2,10]@tcp [ 3545.097824] Lustre: Mounted lustre-client [ 3551.698406] Lustre: Unmounted lustre-client [ 3582.801305] Key type lgssc unregistered [ 3583.185284] LNet: 84165:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3584.225553] LNet: Removed LNI 192.168.202.19@tcp [ 3584.943299] Key type .llcrypt unregistered [ 3584.945295] Key type ._llcrypt unregistered [ 3602.955354] alg: No test for adler32 (adler32-zlib) [ 3603.723979] Key type ._llcrypt registered [ 3603.727332] Key type .llcrypt registered [ 3604.984823] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3606.687261] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 17:44:30 (1776203070) [ 3625.999262] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3626.362103] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 3626.521034] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 3626.524766] LNet: Accept secure, port 988 [ 3628.183120] Key type lgssc registered [ 3629.104847] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3706.851050] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3712.209865] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3734.901201] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3799.232426] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3805.169943] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3825.961347] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3840.297202] Lustre: Mounted lustre-client [ 3866.636409] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8fc544a0c000.ost_server_uuid 40 [ 3867.968132] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8fc544a0c000.ost_server_uuid in FULL state after 0 sec [ 3868.386022] Lustre: Mounted lustre-client [ 3871.767603] Lustre: Unmounted lustre-client [ 3873.677974] Lustre: Unmounted lustre-client [ 3917.523849] Key type lgssc unregistered [ 3917.795313] LNet: 91849:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3918.816365] LNet: Removed LNI 192.168.202.19@tcp [ 3919.586977] Key type .llcrypt unregistered [ 3919.589233] Key type ._llcrypt unregistered [ 3964.017984] alg: No test for adler32 (adler32-zlib) [ 3964.806138] Key type ._llcrypt registered [ 3964.811247] Key type .llcrypt registered [ 3965.109261] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3965.461495] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 3965.724138] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 3965.729844] LNet: Accept secure, port 988 [ 3967.415258] Key type lgssc registered [ 3968.818414] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4054.235702] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4058.942363] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4078.590713] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4117.925689] Lustre: DEBUG MARKER: == conf-sanity test 47: server restart does not make client loss lru_resize settings ========================================================== 17:53:02 (1776203582) [ 4210.926731] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4217.510860] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4240.355373] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4241.864486] Lustre: Mounted lustre-client [ 4254.175315] Lustre: 92633:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776203712/real 1776203712] req@00000000236d7586 x1862484288867008/t0(0) o400->lustre-OST0000-osc-ffff8fc5481bd000@192.168.202.119@tcp:28/4 lens 224/224 e 0 to 1 dl 1776203719 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 4254.198685] Lustre: lustre-OST0000-osc-ffff8fc5481bd000: Connection to lustre-OST0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4259.295308] Lustre: 92633:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776203717/real 1776203717] req@0000000073d539c4 x1862484288867264/t0(0) o400->lustre-OST0000-osc-ffff8fc5481bd000@192.168.202.119@tcp:28/4 lens 224/224 e 0 to 1 dl 1776203724 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 4270.563580] Lustre: lustre-MDT0000-mdc-ffff8fc5481bd000: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4271.724947] Lustre: lustre-OST0000-osc-ffff8fc5481bd000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 4278.751141] Lustre: 92630:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776203737/real 1776203737] req@0000000052e006ee x1862484288868608/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1776203744 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 4278.765484] LustreError: 166-1: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [ 4296.198300] Lustre: Evicted from MGS (at 192.168.202.119@tcp) after server handle changed from 0x988381c08409f164 to 0x988381c08409f308 [ 4296.217879] Lustre: MGC192.168.202.119@tcp: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 4304.773437] Lustre: lustre-MDT0000-mdc-ffff8fc5481bd000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 4312.251550] Lustre: Unmounted lustre-client [ 4346.598154] Key type lgssc unregistered [ 4346.871130] LNet: 100856:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4347.959158] LNet: Removed LNI 192.168.202.19@tcp [ 4348.746583] Key type .llcrypt unregistered [ 4348.752589] Key type ._llcrypt unregistered [ 4364.048447] alg: No test for adler32 (adler32-zlib) [ 4364.811844] Key type ._llcrypt registered [ 4364.813785] Key type .llcrypt registered [ 4366.131240] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 17:57:09 (1776203829) [ 4368.419075] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4368.801600] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 4369.046231] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 4369.057965] LNet: Accept secure, port 988 [ 4370.791194] Key type lgssc registered [ 4372.167731] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4417.652918] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4423.261837] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4443.207630] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4448.799607] Lustre: Mounted lustre-client [ 4499.936854] Lustre: 104819:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776203958/real 1776203958] req@0000000024e0a298 x1862484711701952/t0(0) o101->lustre-MDT0000-mdc-ffff8fc545b96800@192.168.202.119@tcp:12/10 lens 584/7592 e 0 to 1 dl 1776203965 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'' [ 4499.968803] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4500.013222] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection restored to (at 192.168.202.119@tcp) [ 4528.612309] Lustre: 105190:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776203987/real 1776203987] req@00000000df22d60c x1862484711820800/t0(0) o101->lustre-MDT0000-mdc-ffff8fc545b96800@192.168.202.119@tcp:12/10 lens 584/10528 e 0 to 1 dl 1776203994 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'' [ 4528.637414] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4528.705721] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection restored to (at 192.168.202.119@tcp) [ 4543.995494] Lustre: 105360:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776204002/real 1776204002] req@000000000fbc21e0 x1862484711875328/t0(0) o101->lustre-MDT0000-mdc-ffff8fc545b96800@192.168.202.119@tcp:12/10 lens 584/11872 e 0 to 1 dl 1776204009 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'' [ 4544.026046] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4544.119222] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection restored to (at 192.168.202.119@tcp) [ 4563.937961] Lustre: 105584:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776204022/real 1776204022] req@0000000091c69553 x1862484711946944/t0(0) o101->lustre-MDT0000-mdc-ffff8fc545b96800@192.168.202.119@tcp:12/10 lens 584/13632 e 0 to 1 dl 1776204029 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'' [ 4563.986127] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4564.045660] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection restored to (at 192.168.202.119@tcp) [ 4578.783302] Lustre: 105707:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776204037/real 1776204037] req@000000007e2a2299 x1862484711986432/t0(0) o101->lustre-MDT0000-mdc-ffff8fc545b96800@192.168.202.119@tcp:12/10 lens 584/14600 e 0 to 1 dl 1776204044 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'' [ 4578.824630] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4578.886240] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection restored to (at 192.168.202.119@tcp) [ 4595.167201] Lustre: 105880:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776204053/real 1776204053] req@00000000eb9c9ce6 x1862484712041920/t0(0) o101->lustre-MDT0000-mdc-ffff8fc545b96800@192.168.202.119@tcp:12/10 lens 584/15968 e 0 to 1 dl 1776204060 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'' [ 4595.197468] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4595.243910] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection restored to (at 192.168.202.119@tcp) [ 4719.583216] Lustre: 107272:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776204140/real 1776204140] req@0000000036cb2631 x1862484712483456/t0(0) o101->lustre-MDT0000-mdc-ffff8fc545b96800@192.168.202.119@tcp:12/10 lens 584/26912 e 0 to 1 dl 1776204184 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'' [ 4719.628416] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4719.688415] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection restored to (at 192.168.202.119@tcp) [ 4809.695236] Lustre: 107960:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776204228/real 1776204228] req@000000000f16b2d7 x1862484712703936/t0(0) o101->lustre-MDT0000-mdc-ffff8fc545b96800@192.168.202.119@tcp:12/10 lens 584/32320 e 0 to 1 dl 1776204272 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'' [ 4809.740939] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4809.788408] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection restored to (at 192.168.202.119@tcp) [ 4920.289276] Lustre: 108294:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776204338/real 1776204338] req@00000000be6d6999 x1862484712811328/t0(0) o101->lustre-MDT0000-mdc-ffff8fc545b96800@192.168.202.119@tcp:12/10 lens 584/34864 e 0 to 1 dl 1776204382 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'' [ 4920.338903] Lustre: 108294:0:(client.c:2295:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 4920.353348] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4920.365732] Lustre: Skipped 1 previous similar message [ 4920.388598] Lustre: lustre-MDT0000-mdc-ffff8fc545b96800: Connection restored to (at 192.168.202.119@tcp) [ 4920.394517] Lustre: Skipped 1 previous similar message [ 4947.936127] LustreError: 166-1: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [ 4965.359307] Lustre: Evicted from MGS (at 192.168.202.119@tcp) after server handle changed from 0x5ad43ee7ac6ba211 to 0x5ad43ee7ac6e0c87 [ 4979.281904] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4980.910780] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4985.067774] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4995.444031] Lustre: Unmounted lustre-client [ 5115.362441] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5120.538756] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5141.530977] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5178.319292] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 18:10:42 (1776204642) [ 5264.914451] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5272.003640] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5295.355763] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5295.837258] Lustre: Mounted lustre-client [ 5299.995799] Lustre: Unmounted lustre-client [ 5337.053702] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 18:13:21 (1776204801) [ 5430.360284] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5435.023269] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5452.896817] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5453.413659] Lustre: Mounted lustre-client [ 5456.696514] Lustre: Unmounted lustre-client [ 5481.146564] Key type lgssc unregistered [ 5481.378878] LNet: 121287:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5482.410521] LNet: Removed LNI 192.168.202.19@tcp [ 5483.032830] Key type .llcrypt unregistered [ 5483.037685] Key type ._llcrypt unregistered [ 5497.902846] alg: No test for adler32 (adler32-zlib) [ 5498.666298] Key type ._llcrypt registered [ 5498.671336] Key type .llcrypt registered [ 5499.867910] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 18:16:03 (1776204963) [ 5502.434400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5502.757253] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 5503.082735] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 5503.086739] LNet: Accept secure, port 988 [ 5504.783214] Key type lgssc registered [ 5506.255378] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5554.956228] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5560.009816] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5577.194698] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5583.375452] Lustre: Mounted lustre-client [ 5596.590825] Lustre: Unmounted lustre-client [ 5621.399463] Key type lgssc unregistered [ 5621.652495] LNet: 124847:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5622.688209] LNet: Removed LNI 192.168.202.19@tcp [ 5623.290809] Key type .llcrypt unregistered [ 5623.294729] Key type ._llcrypt unregistered [ 5636.336180] alg: No test for adler32 (adler32-zlib) [ 5637.088356] Key type ._llcrypt registered [ 5637.090080] Key type .llcrypt registered [ 5638.178866] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 18:18:21 (1776205101) [ 5640.340945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5640.806601] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 5641.091052] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 5641.095875] LNet: Accept secure, port 988 [ 5642.759168] Key type lgssc registered [ 5643.917806] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5682.510420] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5687.587791] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5707.070537] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5713.436487] Lustre: Mounted lustre-client [ 5718.537845] Lustre: lustre-OST0000-osc-ffff8fc5481bb800: Connection to lustre-OST0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5739.967362] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8fc5481bb800.ost_server_uuid 40 [ 5741.864268] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5753.604843] Lustre: Unmounted lustre-client [ 5773.965851] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 18:20:38 (1776205238) [ 5805.182949] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5810.705188] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5830.233524] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5850.342264] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5850.835731] Lustre: Mounted lustre-client [ 5856.249939] Lustre: lustre-OST0000-osc-ffff8fc545bcc000: Connection to lustre-OST0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5882.309392] Lustre: Unmounted lustre-client [ 5929.137316] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 18:23:13 (1776205393) [ 5962.140088] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5967.780805] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5985.725516] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6003.437438] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6003.975621] Lustre: Mounted lustre-client [ 6009.320723] Lustre: lustre-OST0000-osc-ffff8fc5481be800: Connection to lustre-OST0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6024.289531] Lustre: Unmounted lustre-client [ 6073.566687] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 18:25:37 (1776205537) [ 6156.894581] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6162.971154] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6186.124900] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6249.446655] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6254.651202] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6274.049729] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6309.438623] Lustre: Mounted lustre-client [ 6351.873672] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6353.767387] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6356.970459] Lustre: Unmounted lustre-client [ 6386.675557] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 18:30:51 (1776205851) [ 6415.085381] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6419.519649] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6436.853491] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6466.598351] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6493.690271] Lustre: Mounted lustre-client [ 6535.381542] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6537.170658] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6539.749912] Lustre: lustre-OST0001-osc-ffff8fc545bcd800: Connection to lustre-OST0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6549.404174] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6559.729659] Lustre: Unmounted lustre-client [ 6625.465923] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 18:34:49 (1776206089) [ 6652.724102] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6657.748443] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6674.275774] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6674.827691] Lustre: Mounted lustre-client [ 6694.083702] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6695.789082] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6709.478068] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8fc542e0f000.ost_server_uuid 40 [ 6710.919857] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8fc542e0f000.ost_server_uuid in IDLE state after 0 sec [ 6713.900598] Lustre: Unmounted lustre-client [ 6714.211695] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6714.216355] Lustre: Skipped 3 previous similar messages [ 6714.335759] Lustre: Mounted lustre-client [ 6715.388858] LustreError: 148720:0:(sec.c:411:import_sec_validate_get()) import 000000008a6021fb (NEW) with no sec [ 6771.581810] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 18:37:15 (1776206235) [ 6808.742239] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6813.969600] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6834.490254] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6854.904158] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6855.263507] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6855.428563] Lustre: Mounted lustre-client [ 6864.899202] LustreError: 151758:0:(obd_config.c:1999:class_config_llog_handler()) MGC192.168.202.119@tcp: cfg command failed: rc = -114 [ 6864.913088] Lustre: cmd=cf00f 0:lustre-OST0000-osc 1:osc.active=1 [ 6864.913088] [ 6864.923316] LustreError: 151648:0:(mgc_request.c:614:do_requeue()) failed processing log: -114 [ 6869.565387] Lustre: Unmounted lustre-client [ 6869.568601] Lustre: Skipped 1 previous similar message [ 6911.114777] Key type lgssc unregistered [ 6911.381955] LNet: 152241:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6912.419220] LNet: Removed LNI 192.168.202.19@tcp [ 6913.114470] Key type .llcrypt unregistered [ 6913.118415] Key type ._llcrypt unregistered [ 6927.884757] alg: No test for adler32 (adler32-zlib) [ 6928.638974] Key type ._llcrypt registered [ 6928.643269] Key type .llcrypt registered [ 6930.354860] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 18:39:53 (1776206393) [ 6931.576372] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6931.944608] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 6932.193895] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 6932.198813] LNet: Accept secure, port 988 [ 6933.855186] Key type lgssc registered [ 6934.979953] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6987.142396] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6992.900882] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7012.321230] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7032.062286] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7032.495466] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7033.671728] Lustre: Mounted lustre-client [ 7040.027978] LustreError: lustre-MDT0001-mdc-ffff8fc547c91000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7040.045984] Lustre: lustre-MDT0001-mdc-ffff8fc547c91000: Connection restored to (at 192.168.202.119@tcp) [ 7045.169893] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7046.745884] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7068.695644] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7078.037317] Lustre: Unmounted lustre-client [ 7101.717344] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 18:42:46 (1776206566) [ 7196.343650] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7204.589230] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7228.559219] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7229.164724] Lustre: Mounted lustre-client [ 7256.670218] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7258.089805] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7262.203910] Lustre: lustre-OST0001-osc-ffff8fc549d0f000: Connection to lustre-OST0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7269.747887] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7280.124897] Lustre: Unmounted lustre-client [ 7322.567994] Key type lgssc unregistered [ 7322.796973] LNet: 161820:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7323.813698] LNet: Removed LNI 192.168.202.19@tcp [ 7324.565970] Key type .llcrypt unregistered [ 7324.574858] Key type ._llcrypt unregistered [ 7371.215238] alg: No test for adler32 (adler32-zlib) [ 7371.975399] Key type ._llcrypt registered [ 7371.977588] Key type .llcrypt registered [ 7372.156437] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7372.447941] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 7372.665502] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 7372.668629] LNet: Accept secure, port 988 [ 7374.271132] Key type lgssc registered [ 7375.333824] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7463.949146] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7469.496856] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7494.245736] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7549.757423] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 18:50:13 (1776207013) [ 7584.679311] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7590.508538] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7611.139720] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7613.320511] Lustre: Mounted lustre-client [ 7630.337580] Lustre: Unmounted lustre-client [ 7671.752629] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7672.591162] Lustre: Mounted lustre-client [ 7679.775001] Lustre: Unmounted lustre-client [ 7709.981185] Key type lgssc unregistered [ 7710.212655] LNet: 169184:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7711.265450] LNet: Removed LNI 192.168.202.19@tcp [ 7712.126882] Key type .llcrypt unregistered [ 7712.128436] Key type ._llcrypt unregistered [ 7727.231156] alg: No test for adler32 (adler32-zlib) [ 7727.992491] Key type ._llcrypt registered [ 7728.000022] Key type .llcrypt registered [ 7729.599943] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 18:53:12 (1776207192) [ 7732.421875] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7732.720135] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 7732.886786] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 7732.895241] LNet: Accept secure, port 988 [ 7734.545382] Key type lgssc registered [ 7735.767501] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7785.360352] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7790.426590] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7811.420567] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7813.182527] Lustre: Mounted lustre-client [ 7831.312811] Lustre: Unmounted lustre-client [ 7860.095745] Key type lgssc unregistered [ 7860.305802] LNet: 172909:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7861.346671] LNet: Removed LNI 192.168.202.19@tcp [ 7862.090463] Key type .llcrypt unregistered [ 7862.093624] Key type ._llcrypt unregistered [ 7875.001337] alg: No test for adler32 (adler32-zlib) [ 7875.758526] Key type ._llcrypt registered [ 7875.761832] Key type .llcrypt registered [ 7875.944520] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7876.219617] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 7876.404289] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 7876.407963] LNet: Accept secure, port 988 [ 7878.072211] Key type lgssc registered [ 7879.104258] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7927.723917] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7935.469113] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7958.435863] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7959.547392] Lustre: Mounted lustre-client [ 7966.691512] Lustre: Unmounted lustre-client [ 8003.069278] Key type lgssc unregistered [ 8003.354933] LNet: 175945:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8004.391412] LNet: Removed LNI 192.168.202.19@tcp [ 8005.066720] Key type .llcrypt unregistered [ 8005.070731] Key type ._llcrypt unregistered [ 8017.501494] alg: No test for adler32 (adler32-zlib) [ 8018.272292] Key type ._llcrypt registered [ 8018.275765] Key type .llcrypt registered [ 8018.551687] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8018.857398] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 8019.055099] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 8019.060033] LNet: Accept secure, port 988 [ 8020.753513] Key type lgssc registered [ 8021.842116] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8072.698927] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8079.551937] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8098.239039] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8099.763755] Lustre: Mounted lustre-client [ 8103.519430] Lustre: Unmounted lustre-client [ 8134.713458] Key type lgssc unregistered [ 8134.930813] LNet: 178930:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8135.969494] LNet: Removed LNI 192.168.202.19@tcp [ 8136.544366] Key type .llcrypt unregistered [ 8136.547128] Key type ._llcrypt unregistered [ 8154.736065] alg: No test for adler32 (adler32-zlib) [ 8155.496126] Key type ._llcrypt registered [ 8155.499035] Key type .llcrypt registered [ 8157.456177] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 19:00:19 (1776207619) [ 8160.165220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8160.522711] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 8160.728608] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 8160.737921] LNet: Accept secure, port 988 [ 8162.391512] Key type lgssc registered [ 8163.450474] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8214.468485] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8221.000643] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8241.371966] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8242.954109] Lustre: Mounted lustre-client [ 8263.391218] Lustre: Unmounted lustre-client [ 8290.948739] Key type lgssc unregistered [ 8291.164350] LNet: 182671:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8292.193346] LNet: Removed LNI 192.168.202.19@tcp [ 8293.011889] Key type .llcrypt unregistered [ 8293.014286] Key type ._llcrypt unregistered [ 8307.858591] alg: No test for adler32 (adler32-zlib) [ 8308.686389] Key type ._llcrypt registered [ 8308.692042] Key type .llcrypt registered [ 8308.940612] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8309.269823] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 8309.574857] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 8309.586806] LNet: Accept secure, port 988 [ 8311.255127] Key type lgssc registered [ 8312.973814] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8361.417355] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8366.423495] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8385.166514] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8391.206327] Lustre: Mounted lustre-client [ 8398.162272] Lustre: Unmounted lustre-client [ 8425.353960] Key type lgssc unregistered [ 8425.554801] LNet: 185704:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8426.593721] LNet: Removed LNI 192.168.202.19@tcp [ 8427.340030] Key type .llcrypt unregistered [ 8427.342922] Key type ._llcrypt unregistered [ 8437.573029] alg: No test for adler32 (adler32-zlib) [ 8438.333052] Key type ._llcrypt registered [ 8438.340369] Key type .llcrypt registered [ 8438.534941] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8438.816795] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 8438.975200] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 8438.978455] LNet: Accept secure, port 988 [ 8440.623189] Key type lgssc registered [ 8442.034436] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8493.901929] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8499.234386] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8519.192126] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8521.029874] Lustre: Mounted lustre-client [ 8525.386155] Lustre: Unmounted lustre-client [ 8559.618513] Key type lgssc unregistered [ 8559.899937] LNet: 188687:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8560.929615] LNet: Removed LNI 192.168.202.19@tcp [ 8561.518244] Key type .llcrypt unregistered [ 8561.527980] Key type ._llcrypt unregistered [ 8579.489191] alg: No test for adler32 (adler32-zlib) [ 8580.246302] Key type ._llcrypt registered [ 8580.250915] Key type .llcrypt registered [ 8581.847725] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 19:07:24 (1776208044) [ 8606.609818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8606.860481] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 8607.103471] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 8607.126401] LNet: Accept secure, port 988 [ 8608.863359] Key type lgssc registered [ 8610.241524] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8686.559095] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8692.821658] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8714.631528] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8760.468276] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 19:10:24 (1776208224) [ 8796.895185] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8802.456435] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8820.617987] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8822.131923] Lustre: Mounted lustre-client [ 8826.647660] Lustre: Unmounted lustre-client [ 8852.755413] Key type lgssc unregistered [ 8853.028922] LNet: 195782:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8854.047901] LNet: Removed LNI 192.168.202.19@tcp [ 8854.795657] Key type .llcrypt unregistered [ 8854.798976] Key type ._llcrypt unregistered [ 8868.968268] alg: No test for adler32 (adler32-zlib) [ 8869.726072] Key type ._llcrypt registered [ 8869.736262] Key type .llcrypt registered [ 8871.010223] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8872.432856] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 19:12:16 (1776208336) [ 8889.847626] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8890.206597] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 8890.415742] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 8890.428236] LNet: Accept secure, port 988 [ 8892.057332] Key type lgssc registered [ 8893.072904] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8973.610140] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8978.923917] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8999.706936] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 9016.823612] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 9018.414401] Lustre: Mounted lustre-client [ 9039.421186] Lustre: Unmounted lustre-client [ 9159.101822] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9164.922852] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9187.345874] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9229.347056] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 19:18:13 (1776208693) [ 9231.712232] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9233.689589] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 19:18:17 (1776208697) [ 9293.976288] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9299.244519] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9325.173192] Key type lgssc unregistered [ 9325.437905] LNet: 207624:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9326.497518] LNet: Removed LNI 192.168.202.19@tcp [ 9327.450196] Key type .llcrypt unregistered [ 9327.459393] Key type ._llcrypt unregistered [ 9344.321699] alg: No test for adler32 (adler32-zlib) [ 9345.097043] Key type ._llcrypt registered [ 9345.105340] Key type .llcrypt registered [ 9346.747982] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 19:20:09 (1776208809) [ 9379.822419] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9380.377181] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 9380.639844] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 9380.651420] LNet: Accept secure, port 988 [ 9382.407343] Key type lgssc registered [ 9383.479731] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9429.780339] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9437.089303] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9459.402805] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9487.708471] Key type lgssc unregistered [ 9487.930669] LNet: 211312:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9487.944924] LNet: Removed LNI 192.168.202.19@tcp [ 9488.606475] Key type .llcrypt unregistered [ 9488.616462] Key type ._llcrypt unregistered [ 9504.885910] alg: No test for adler32 (adler32-zlib) [ 9505.647686] Key type ._llcrypt registered [ 9505.654480] Key type .llcrypt registered [ 9507.420449] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 19:22:50 (1776208970) [ 9510.253982] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9510.591489] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 9510.809078] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 9510.813988] LNet: Accept secure, port 988 [ 9512.504985] Key type lgssc registered [ 9513.861912] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9575.958776] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9583.126197] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9602.792350] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9604.510044] Lustre: Mounted lustre-client [ 9619.949820] Lustre: lustre-MDT0000-mdc-ffff8fc5614b3800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9627.103247] Lustre: 212284:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776209085/real 1776209085] req@00000000e29d6a65 x1862490103259136/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1776209092 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 9627.138302] LustreError: 166-1: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [ 9659.846177] Lustre: Evicted from MGS (at 192.168.202.119@tcp) after server handle changed from 0x2558157aa13616ba to 0x2558157aa13631a9 [ 9659.879017] Lustre: MGC192.168.202.119@tcp: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 9673.306872] LustreError: lustre-MDT0001-mdc-ffff8fc5614b3800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 9673.318850] Lustre: lustre-MDT0001-mdc-ffff8fc5614b3800: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 9679.357021] LustreError: lustre-MDT0000-mdc-ffff8fc5614b3800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9679.412237] Lustre: lustre-MDT0000-mdc-ffff8fc5614b3800: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 9688.167141] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9690.321172] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9696.863384] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9698.754890] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9702.695198] Lustre: Unmounted lustre-client [ 9732.091099] Key type lgssc unregistered [ 9732.317976] LNet: 216312:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9733.350069] LNet: Removed LNI 192.168.202.19@tcp [ 9734.081833] Key type .llcrypt unregistered [ 9734.084665] Key type ._llcrypt unregistered [ 9750.147321] alg: No test for adler32 (adler32-zlib) [ 9750.900429] Key type ._llcrypt registered [ 9750.909290] Key type .llcrypt registered [ 9752.491843] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 19:26:55 (1776209215) [ 9755.521865] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9755.800480] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [ 9755.994632] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 9755.998745] LNet: Accept secure, port 988 [ 9757.703274] Key type lgssc registered [ 9758.912582] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9809.978219] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9817.097771] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9879.892189] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9887.726682] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9920.410149] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9946.470959] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9994.643965] Key type lgssc unregistered [ 9994.952958] LNet: 221616:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9994.964648] LNet: Removed LNI 192.168.202.19@tcp [ 9995.663286] Key type .llcrypt unregistered [ 9995.666271] Key type ._llcrypt unregistered [10043.817231] alg: No test for adler32 (adler32-zlib) [10044.582306] Key type ._llcrypt registered [10044.585278] Key type .llcrypt registered [10044.790353] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10045.128299] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [10045.398225] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [10045.407779] LNet: Accept secure, port 988 [10047.095255] Key type lgssc registered [10048.725479] Lustre: Echo OBD driver; http://www.lustre.org/ [10132.773764] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10138.430580] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10159.956158] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10210.567788] Lustre: DEBUG MARKER: == conf-sanity test 60: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 19:34:34 (1776209674) [10326.723723] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10332.574405] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10352.509183] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10395.053955] Lustre: DEBUG MARKER: == conf-sanity test 61: large xattr ====================== 19:37:38 (1776209858) [10434.737656] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10439.941800] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10458.073162] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10459.625849] Lustre: Mounted lustre-client [10465.161527] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61.conf-sanity [10467.111860] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61.conf-sanity [10468.656710] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61.conf-sanity [10470.129374] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61.conf-sanity after remounting MDS [10474.986338] Lustre: lustre-MDT0000-mdc-ffff8fc544a0d000: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [10482.143837] Lustre: 222398:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776209940/real 1776209940] req@00000000ff0c6b8a x1862490663162496/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1776209947 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [10482.177893] LustreError: 166-1: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [10499.574133] Lustre: Evicted from MGS (at 192.168.202.119@tcp) after server handle changed from 0xfa2d103ee91e00 to 0xfa2d103ee91f42 [10499.589876] Lustre: MGC192.168.202.119@tcp: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [10506.617325] Lustre: lustre-MDT0000-mdc-ffff8fc544a0d000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [10516.118378] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10517.956953] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10521.706234] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61.conf-sanity [10525.155990] Lustre: lustre-MDT0000-mdc-ffff8fc544a0d000: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [10532.319166] Lustre: 222397:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776209990/real 1776209990] req@00000000518ae929 x1862490663167232/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1776209997 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [10532.343549] LustreError: 166-1: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [10548.727621] Lustre: Evicted from MGS (at 192.168.202.119@tcp) after server handle changed from 0xfa2d103ee91f42 to 0xfa2d103ee92244 [10548.740261] Lustre: MGC192.168.202.119@tcp: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [10556.791095] Lustre: lustre-MDT0000-mdc-ffff8fc544a0d000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [10565.404315] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10567.231363] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10570.354659] Lustre: Unmounted lustre-client [10648.935777] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10655.718201] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10677.194941] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10677.861547] Lustre: Mounted lustre-client [10689.862907] Lustre: Unmounted lustre-client [10718.912718] Key type lgssc unregistered [10719.163940] LNet: 237113:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10720.225797] LNet: Removed LNI 192.168.202.19@tcp [10720.874878] Key type .llcrypt unregistered [10720.876138] Key type ._llcrypt unregistered [10735.153717] alg: No test for adler32 (adler32-zlib) [10735.912758] Key type ._llcrypt registered [10735.915220] Key type .llcrypt registered [10737.189849] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 19:43:20 (1776210200) [10740.423658] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10740.684274] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [10740.842974] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [10740.846426] LNet: Accept secure, port 988 [10742.479223] Key type lgssc registered [10743.829948] Lustre: Echo OBD driver; http://www.lustre.org/ [10794.482230] Key type lgssc unregistered [10794.632701] LNet: 239273:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10794.637774] LNet: Removed LNI 192.168.202.19@tcp [10795.275511] Key type .llcrypt unregistered [10795.277888] Key type ._llcrypt unregistered [10826.802809] alg: No test for adler32 (adler32-zlib) [10827.673832] Key type ._llcrypt registered [10827.677621] Key type .llcrypt registered [10827.879890] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10828.170692] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [10828.347817] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [10828.351415] LNet: Accept secure, port 988 [10830.007671] Key type lgssc registered [10831.554720] Lustre: Echo OBD driver; http://www.lustre.org/ [10917.777244] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10925.619181] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10955.207666] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11008.538534] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11010.321607] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 19:47:54 (1776210474) [11045.569719] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11052.216469] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11073.070880] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11097.857735] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [11099.875521] Lustre: Mounted lustre-client [11105.288647] Lustre: lustre-OST0001-osc-ffff8fc54471f000: Connection to lustre-OST0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [11112.452299] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11122.793726] Lustre: Unmounted lustre-client [11161.453871] Key type lgssc unregistered [11161.686625] LNet: 246396:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11162.721748] LNet: Removed LNI 192.168.202.19@tcp [11163.488471] Key type .llcrypt unregistered [11163.491620] Key type ._llcrypt unregistered [11212.770857] alg: No test for adler32 (adler32-zlib) [11213.530497] Key type ._llcrypt registered [11213.533100] Key type .llcrypt registered [11213.784579] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11214.172933] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [11214.379652] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [11214.384761] LNet: Accept secure, port 988 [11216.047109] Key type lgssc registered [11217.404706] Lustre: Echo OBD driver; http://www.lustre.org/ [11308.382151] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11318.373478] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11344.568644] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11396.229896] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 19:54:20 (1776210860) [11451.683344] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11458.700424] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11484.833932] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 19:55:48 (1776210948) [11522.432632] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11529.941525] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11550.261641] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11551.724215] Lustre: Mounted lustre-client [11560.379118] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11569.005267] Lustre: Unmounted lustre-client [11677.219473] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11685.567378] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11739.152922] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11739.706970] Lustre: Mounted lustre-client [11747.687284] Lustre: Unmounted lustre-client [11778.539918] Key type lgssc unregistered [11778.882209] LNet: 257124:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11779.956491] LNet: Removed LNI 192.168.202.19@tcp [11781.030971] Key type .llcrypt unregistered [11781.041154] Key type ._llcrypt unregistered [11814.095932] alg: No test for adler32 (adler32-zlib) [11814.852163] Key type ._llcrypt registered [11814.857225] Key type .llcrypt registered [11815.134712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11815.465327] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [11815.704883] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [11815.709968] LNet: Accept secure, port 988 [11817.415228] Key type lgssc registered [11818.642461] Lustre: Echo OBD driver; http://www.lustre.org/ [11905.771567] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11913.852711] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11936.837291] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11982.789715] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11984.294190] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 20:04:08 (1776211448) [12015.114915] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12021.714731] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12048.882827] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12053.786147] Lustre: Mounted lustre-client [12059.437195] Lustre: Unmounted lustre-client [12090.403967] Key type lgssc unregistered [12090.851253] LNet: 263772:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12091.876151] LNet: Removed LNI 192.168.202.19@tcp [12093.242954] Key type .llcrypt unregistered [12093.246182] Key type ._llcrypt unregistered [12112.907134] alg: No test for adler32 (adler32-zlib) [12113.669352] Key type ._llcrypt registered [12113.674838] Key type .llcrypt registered [12115.419046] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12117.280039] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 20:06:21 (1776211581) [12126.378725] Key type .llcrypt unregistered [12126.380938] Key type ._llcrypt unregistered [12136.277713] alg: No test for adler32 (adler32-zlib) [12137.086556] Key type ._llcrypt registered [12137.091110] Key type .llcrypt registered [12137.456693] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12137.961451] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [12138.214134] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [12138.220687] LNet: Accept secure, port 988 [12139.871103] Key type lgssc registered [12141.019426] Lustre: Echo OBD driver; http://www.lustre.org/ [12194.733270] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12214.528777] Lustre: Mounted lustre-client [12218.216598] Lustre: Unmounted lustre-client [12244.982769] Key type lgssc unregistered [12245.288941] LNet: 267105:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12246.304813] LNet: Removed LNI 192.168.202.19@tcp [12247.163719] Key type .llcrypt unregistered [12247.166668] Key type ._llcrypt unregistered [12266.920916] alg: No test for adler32 (adler32-zlib) [12267.706434] Key type ._llcrypt registered [12267.708338] Key type .llcrypt registered [12269.405950] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 20:08:52 (1776211732) [12272.678107] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12273.537890] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [12273.992553] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [12273.999482] LNet: Accept secure, port 988 [12275.848069] Key type lgssc registered [12277.595870] Lustre: Echo OBD driver; http://www.lustre.org/ [12398.572530] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12436.635702] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12442.653683] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12444.191953] Lustre: Mounted lustre-client [12447.144416] Lustre: Unmounted lustre-client [12472.418850] Key type lgssc unregistered [12472.726097] LNet: 270617:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12473.764156] LNet: Removed LNI 192.168.202.19@tcp [12474.665748] Key type .llcrypt unregistered [12474.671680] Key type ._llcrypt unregistered [12492.001670] alg: No test for adler32 (adler32-zlib) [12492.756818] Key type ._llcrypt registered [12492.766493] Key type .llcrypt registered [12494.342518] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 20:12:37 (1776211957) [12496.897752] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12497.568438] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [12497.803312] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [12497.812343] LNet: Accept secure, port 988 [12499.551784] Key type lgssc registered [12501.223308] Lustre: Echo OBD driver; http://www.lustre.org/ [12554.332185] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12560.690921] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12581.246381] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12582.987740] Lustre: Mounted lustre-client [12588.045498] Lustre: lustre-MDT0000-mdc-ffff8fc5614b1800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [12590.566270] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12590.615432] LustreError: 273742:0:(file.c:5205:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12591.122366] LustreError: 166-1: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [12591.183447] Lustre: Unmounted lustre-client [12614.630605] Key type lgssc unregistered [12614.845870] LNet: 274158:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12615.909827] LNet: Removed LNI 192.168.202.19@tcp [12616.796318] Key type .llcrypt unregistered [12616.798518] Key type ._llcrypt unregistered [12633.182538] alg: No test for adler32 (adler32-zlib) [12633.938715] Key type ._llcrypt registered [12633.941295] Key type .llcrypt registered [12635.591448] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 20:14:58 (1776212098) [12638.825750] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12639.246973] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [12639.631455] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [12639.636190] LNet: Accept secure, port 988 [12641.383274] Key type lgssc registered [12643.042199] Lustre: Echo OBD driver; http://www.lustre.org/ [12699.209578] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12705.638876] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12725.172831] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12726.809759] Lustre: Mounted lustre-client [12731.884139] Lustre: lustre-MDT0001-mdc-ffff8fc549db5800: Connection to lustre-MDT0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [12737.320187] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12739.426691] Lustre: Unmounted lustre-client [12766.088976] Key type lgssc unregistered [12766.356914] LNet: 277706:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12767.391889] LNet: Removed LNI 192.168.202.19@tcp [12768.130553] Key type .llcrypt unregistered [12768.135818] Key type ._llcrypt unregistered [12788.600402] alg: No test for adler32 (adler32-zlib) [12789.355400] Key type ._llcrypt registered [12789.356919] Key type .llcrypt registered [12790.332891] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 20:17:33 (1776212253) [12814.906474] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12815.481118] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [12815.719734] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [12815.722782] LNet: Accept secure, port 988 [12817.415739] Key type lgssc registered [12818.377414] Lustre: Echo OBD driver; http://www.lustre.org/ [12932.443951] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12933.741577] Lustre: Mounted lustre-client [12974.572564] Lustre: lustre-MDT0001-mdc-ffff8fc545bb9000: Connection to lustre-MDT0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [12984.169470] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12994.553359] Lustre: Unmounted lustre-client [13037.871578] Key type lgssc unregistered [13038.113608] LNet: 283169:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13039.153549] LNet: Removed LNI 192.168.202.19@tcp [13039.771660] Key type .llcrypt unregistered [13039.773890] Key type ._llcrypt unregistered [13057.688514] alg: No test for adler32 (adler32-zlib) [13058.446172] Key type ._llcrypt registered [13058.449411] Key type .llcrypt registered [13060.430658] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 20:22:02 (1776212522) [13062.596911] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13065.172473] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 20:22:08 (1776212528) [13067.580338] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13069.480851] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 20:22:13 (1776212533) [13071.509952] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13073.594624] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 20:22:17 (1776212537) [13075.522871] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13077.547814] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 20:22:21 (1776212541) [13079.475367] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13081.435833] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 20:22:25 (1776212545) [13091.648540] Key type .llcrypt unregistered [13091.652652] Key type ._llcrypt unregistered [13101.694880] alg: No test for adler32 (adler32-zlib) [13102.450240] Key type ._llcrypt registered [13102.454414] Key type .llcrypt registered [13102.669815] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13102.923392] Lustre: Lustre: Build Version: 2.15.8_2_g69e1f89 [13103.178375] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [13103.181712] LNet: Accept secure, port 988 [13104.928381] Key type lgssc registered [13106.172456] Lustre: Echo OBD driver; http://www.lustre.org/ [13185.127127] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13193.713613] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13222.577851] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13224.190732] Lustre: Mounted lustre-client [13226.702833] Lustre: Unmounted lustre-client [13261.964982] Lustre: DEBUG MARKER: == conf-sanity test 73: failnode to update from mountdata properly ========================================================== 20:25:25 (1776212725) [13301.018921] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13306.866486] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13333.684211] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13334.686240] Lustre: Mounted lustre-client [13337.020547] Lustre: Unmounted lustre-client [13372.614915] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 20:27:16 (1776212836) [13484.082984] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13491.257751] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13517.935965] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13642.058139] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 13411 sec ========= 20:31:45 (1776213105) [13682.531073] Key type lgssc unregistered [13682.808808] LNet: 298994:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13683.875514] LNet: Removed LNI 192.168.202.19@tcp [13684.359672] Key type .llcrypt unregistered [13684.361159] Key type ._llcrypt unregistered