[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 407919541 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002375] x2apic enabled [ 0.003010] Switched APIC routing to physical x2apic. [ 0.004013] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007029] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008017] pid_max: default: 32768 minimum: 301 [ 0.009171] LSM: Security Framework initializing [ 0.010073] Yama: becoming mindful. [ 0.011059] SELinux: Initializing. [ 0.012099] *** VALIDATE selinux *** [ 0.021856] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026885] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028189] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029128] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031041] *** VALIDATE tmpfs *** [ 0.033043] *** VALIDATE proc *** [ 0.034338] *** VALIDATE cgroup *** [ 0.035016] *** VALIDATE cgroup2 *** [ 0.037036] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038165] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039015] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040041] Spectre V2 : User space: Vulnerable [ 0.041014] Speculative Store Bypass: Vulnerable [ 0.044704] debug: unmapping init [mem 0xffffffff9d459000-0xffffffff9d460fff] [ 0.046812] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047797] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048033] ... version: 2 [ 0.049030] ... bit width: 48 [ 0.050019] ... generic registers: 4 [ 0.051020] ... value mask: 0000ffffffffffff [ 0.052023] ... max period: 00007fffffffffff [ 0.053020] ... fixed-purpose events: 3 [ 0.054017] ... event mask: 000000070000000f [ 0.056317] rcu: Hierarchical SRCU implementation. [ 0.058677] smp: Bringing up secondary CPUs ... [ 0.059698] x86: Booting SMP configuration: [ 0.060040] .... node #0, CPUs: #1 #2 #3 [ 0.063737] smp: Brought up 1 node, 4 CPUs [ 0.065017] smpboot: Max logical packages: 1 [ 0.066033] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.237544] node 0 deferred pages initialised in 169ms [ 0.240138] devtmpfs: initialized [ 0.241284] x86/mm: Memory block size: 128MB [ 0.243782] gcov: version magic: 0x41383552 [ 0.246406] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.247097] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.248442] pinctrl core: initialized pinctrl subsystem [ 0.249312] [ 0.249937] ************************************************************* [ 0.250028] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.251026] ** ** [ 0.252020] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.253020] ** ** [ 0.254017] ** This means that this kernel is built to expose internal ** [ 0.255014] ** IOMMU data structures, which may compromise security on ** [ 0.256012] ** your system. ** [ 0.257013] ** ** [ 0.258019] ** If you see this message and you are not debugging the ** [ 0.259016] ** kernel, report this immediately to your vendor! ** [ 0.260017] ** ** [ 0.261018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.262017] ************************************************************* [ 0.263993] NET: Registered protocol family 16 [ 0.266729] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.269091] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.273092] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.277016] cpuidle: using governor menu [ 0.278757] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.281424] PCI: Using configuration type 1 for base access [ 0.283129] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.291145] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.294038] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.297109] cryptd: max_cpu_qlen set to 1000 [ 0.299143] ACPI: Added _OSI(Module Device) [ 0.301015] ACPI: Added _OSI(Processor Device) [ 0.302011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.304015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.307936] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.313460] ACPI: Interpreter enabled [ 0.314092] ACPI: PM: (supports S0 S3 S4 S5) [ 0.316016] ACPI: Using IOAPIC for interrupt routing [ 0.317116] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.321501] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.332209] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.334033] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.336016] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.339072] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.343230] acpiphp: Slot [2] registered [ 0.346146] acpiphp: Slot [5] registered [ 0.347144] acpiphp: Slot [6] registered [ 0.349123] acpiphp: Slot [3] registered [ 0.351098] acpiphp: Slot [4] registered [ 0.352107] acpiphp: Slot [7] registered [ 0.354110] acpiphp: Slot [8] registered [ 0.356106] acpiphp: Slot [9] registered [ 0.358120] acpiphp: Slot [10] registered [ 0.359081] acpiphp: Slot [11] registered [ 0.360084] acpiphp: Slot [12] registered [ 0.362111] acpiphp: Slot [13] registered [ 0.363075] acpiphp: Slot [14] registered [ 0.364105] acpiphp: Slot [15] registered [ 0.366116] acpiphp: Slot [16] registered [ 0.367081] acpiphp: Slot [17] registered [ 0.369144] acpiphp: Slot [18] registered [ 0.370081] acpiphp: Slot [19] registered [ 0.371100] acpiphp: Slot [20] registered [ 0.373108] acpiphp: Slot [21] registered [ 0.374110] acpiphp: Slot [22] registered [ 0.375097] acpiphp: Slot [23] registered [ 0.377103] acpiphp: Slot [24] registered [ 0.379096] acpiphp: Slot [25] registered [ 0.380089] acpiphp: Slot [26] registered [ 0.382107] acpiphp: Slot [27] registered [ 0.383097] acpiphp: Slot [28] registered [ 0.385106] acpiphp: Slot [29] registered [ 0.386081] acpiphp: Slot [30] registered [ 0.388115] acpiphp: Slot [31] registered [ 0.390075] PCI host bridge to bus 0000:00 [ 0.391019] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.394034] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.397024] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.400023] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.403022] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.406026] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.409164] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.412085] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.415285] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.423025] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.427056] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.431056] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.434020] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.436020] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.439624] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.442991] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.447062] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.450985] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.456009] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.466012] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.470024] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.476086] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.484017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.491017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.509016] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.518783] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.525017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.534015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.546913] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.556932] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.559360] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.562521] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.564314] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.566223] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.570162] iommu: Default domain type: Passthrough [ 0.573588] SCSI subsystem initialized [ 0.575228] ACPI: bus type USB registered [ 0.577145] usbcore: registered new interface driver usbfs [ 0.579118] usbcore: registered new interface driver hub [ 0.582122] usbcore: registered new device driver usb [ 0.584231] pps_core: LinuxPPS API ver. 1 registered [ 0.587016] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.591113] PTP clock support registered [ 0.593154] EDAC MC: Ver: 3.0.0 [ 0.596127] PCI: Using ACPI for IRQ routing [ 0.600113] NetLabel: Initializing [ 0.602014] NetLabel: domain hash size = 128 [ 0.603013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.606113] NetLabel: unlabeled traffic allowed by default [ 0.609246] vgaarb: loaded [ 0.611420] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.613021] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.620000] clocksource: Switched to clocksource kvm-clock [ 0.723763] VFS: Disk quotas dquot_6.6.0 [ 0.725472] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.727839] *** VALIDATE ramfs *** [ 0.729045] *** VALIDATE hugetlbfs *** [ 0.730574] pnp: PnP ACPI init [ 0.732877] pnp: PnP ACPI: found 6 devices [ 0.750162] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.753533] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.755700] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.758174] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.760288] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.762470] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.764834] NET: Registered protocol family 2 [ 0.767409] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.772299] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.775770] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.781690] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.785702] TCP: Hash tables configured (established 65536 bind 65536) [ 0.789197] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.792450] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.795411] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.798401] NET: Registered protocol family 1 [ 0.801304] RPC: Registered named UNIX socket transport module. [ 0.804078] RPC: Registered udp transport module. [ 0.806197] RPC: Registered tcp transport module. [ 0.808208] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.810849] NET: Registered protocol family 44 [ 0.812825] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.815050] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.817644] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.819924] PCI: CLS 0 bytes, default 64 [ 0.821415] Unpacking initramfs... [ 2.244452] debug: unmapping init [mem 0xffff9b3dbcc64000-0xffff9b3dbffcffff] [ 2.249091] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.251779] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.254507] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.765938] Initialise system trusted keyrings [ 2.767410] Key type blacklist registered [ 2.771737] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.781325] zbud: loaded [ 2.785074] *** VALIDATE nfs *** [ 2.786572] *** VALIDATE nfs4 *** [ 2.788862] pstore: using deflate compression [ 2.792908] Platform Keyring initialized [ 2.892294] NET: Registered protocol family 38 [ 2.894029] Key type asymmetric registered [ 2.895402] Asymmetric key parser 'x509' registered [ 2.897199] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.900065] io scheduler mq-deadline registered [ 2.901822] io scheduler kyber registered [ 2.903833] io scheduler bfq registered [ 2.905883] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.908721] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.911537] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.914450] ACPI: Power Button [PWRF] [ 2.921164] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.928084] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.937569] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.965152] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.993957] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.999378] Non-volatile memory driver v1.3 [ 3.001303] Linux agpgart interface v0.103 [ 3.032252] virtio_blk virtio1: [vda] 145888 512-byte logical blocks (74.7 MB/71.2 MiB) [ 3.034953] vda: detected capacity change from 0 to 74694656 [ 3.049659] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.052524] vdb: detected capacity change from 0 to 1073741824 [ 3.061320] libphy: Fixed MDIO Bus: probed [ 3.073779] usbcore: registered new interface driver usbserial_generic [ 3.076435] usbserial: USB Serial support registered for generic [ 3.078893] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.082425] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.084135] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.086845] mousedev: PS/2 mouse device common for all mice [ 3.090425] rtc_cmos 00:05: RTC can wake from S4 [ 3.093458] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.093769] rtc_cmos 00:05: registered as rtc0 [ 3.099530] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.100414] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.103614] intel_pstate: CPU model not supported [ 3.108952] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.114214] hid: raw HID events driver (C) Jiri Kosina [ 3.116184] usbcore: registered new interface driver usbhid [ 3.117892] usbhid: USB HID core driver [ 3.119394] drop_monitor: Initializing network drop monitor service [ 3.121775] Initializing XFRM netlink socket [ 3.124306] NET: Registered protocol family 10 [ 3.127595] Segment Routing with IPv6 [ 3.129236] NET: Registered protocol family 17 [ 3.131515] mpls_gso: MPLS GSO support [ 3.137733] RAS: Correctable Errors collector initialized. [ 3.139930] AVX version of gcm_enc/dec engaged. [ 3.141872] AES CTR mode by8 optimization enabled [ 3.212972] sched_clock: Marking stable (3212934356, 0)->(4133043911, -920109555) [ 3.217244] registered taskstats version 1 [ 3.219691] Loading compiled-in X.509 certificates [ 3.222339] zswap: loaded using pool lzo/zbud [ 3.248952] Key type big_key registered [ 3.261841] Key type encrypted registered [ 3.263415] ima: No TPM chip found, activating TPM-bypass! [ 3.265995] ima: Allocated hash algorithm: sha1 [ 3.267676] ima: No architecture policies found [ 3.269652] evm: Initialising EVM extended attributes: [ 3.272434] evm: security.selinux [ 3.273942] evm: security.ima [ 3.275249] evm: security.capability [ 3.276911] evm: HMAC attrs: 0x1 [ 3.279350] rtc_cmos 00:05: setting system clock to 2026-08-15 13:28:58 UTC (1786800538) [ 3.284925] debug: unmapping init [mem 0xffffffff9e403000-0xffffffff9e5fffff] [ 3.287274] debug: unmapping init [mem 0xffffffff9d182000-0xffffffff9d458fff] [ 3.299088] Write protecting the kernel read-only data: 28672k [ 3.303123] debug: unmapping init [mem 0xffffffff9b803000-0xffffffff9b9fffff] [ 3.305537] debug: unmapping init [mem 0xffffffff9c114000-0xffffffff9c1fffff] [ 3.334665] 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.343559] systemd[1]: Detected virtualization kvm. [ 3.345492] systemd[1]: Detected architecture x86-64. [ 3.347594] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.377664] systemd[1]: No hostname configured. [ 3.379726] systemd[1]: Set hostname to . [ 3.382137] random: systemd: uninitialized urandom read (16 bytes read) [ 3.384479] systemd[1]: Initializing machine ID from random generator. [ 3.428711] random: ln: uninitialized urandom read (6 bytes read) [ 3.512565] random: systemd: uninitialized urandom read (16 bytes read) [ 3.516456] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.521914] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.527039] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Timers. Starting Create Volatile Files and Directories... [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.057665] device-mapper: uevent: version 1.0.3 [ 4.060380] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.718433] virtio_net virtio0 ens2: renamed from eth0 [ 4.820465] scsi host0: ata_piix [ 4.856950] scsi host1: ata_piix [ 4.858854] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.862034] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.394995] dracut-initqueue[585]: RTNETLINK answers: File exists [ 9.781552] random: crng init done [ 9.782935] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.057156] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Sockets. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.131233] printk: systemd: 23 output lines suppressed due to ratelimiting [ 11.382504] SELinux: Disabled at runtime. [ 11.442233] 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.451958] systemd[1]: Detected virtualization kvm. [ 11.454353] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.955456] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.960526] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.971494] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.975441] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.979075] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.987232] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.999103] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-getty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-serial\x2dgetty.slice. Activating swap /dev/disk/by-label/SWAP... Mounting Huge Pages File System... [ OK ] Reached target Slices. Mounting Kernel Debug File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting POSIX Message Queue File System... [ OK ] Listening on udev Kernel Socket. [ 12.054693] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting udev Coldplug all Devices... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... [ OK ] Listening on Process Core Dump Socket. Starting Remount Root and Kernel File Systems... [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.469725] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.777452] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.800725] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.966160] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.974263] EDAC sbridge: Ver: 1.1.2 [ 14.129852] Key type dns_resolver registered [ 14.430314] NFS: Registering the id_resolver key type [ 14.432714] Key type id_resolver registered [ 14.434832] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started 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 Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started OpenSSH server daemon. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg341-client login: [ 73.422162] libcfs: loading out-of-tree module taints kernel. [ 73.495709] Key type ._llcrypt registered [ 73.530246] Key type .llcrypt registered [ 74.263922] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 74.288093] alg: No test for adler32 (adler32-zlib) [ 75.899747] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 76.839311] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 78.615678] Key type lgssc registered [ 80.461234] Lustre: Echo OBD driver; http://www.lustre.org/ [ 245.496552] Lustre: Mounted lustre-client [ 247.272006] hrtimer: interrupt took 4572027 ns [ 250.405732] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 262.411743] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing check_logdir /tmp/testlogs/ [ 267.001150] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing yml_node [ 271.287612] Lustre: DEBUG MARKER: Client: 2.17.56.1 [ 271.331314] Lustre: lustre-OST0000-osc-ffff9b3e21518800: disconnect after 23s idle [ 274.924500] Lustre: DEBUG MARKER: MDS: 2.17.56.1 [ 277.869423] Lustre: DEBUG MARKER: OSS: 2.17.56.1 [ 279.035521] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sat Aug 15 09:33:33 EDT 2026 [ 298.870765] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 303.180398] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 308.409468] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 318.796871] Lustre: Unmounted lustre-client [ 431.701445] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 437.471204] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 459.657388] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 504.945785] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 09:37:18 (1786801038) [ 537.075595] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 542.319405] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 562.284092] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 563.350077] Lustre: Mounted lustre-client [ 568.055912] Lustre: Unmounted lustre-client [ 596.188546] Key type lgssc unregistered [ 596.449627] LNet: 14669:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 596.454337] LNetError: 14669:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 596.482518] LNet: Removed LNI 192.168.203.41@tcp [ 597.322149] Key type .llcrypt unregistered [ 597.326929] Key type ._llcrypt unregistered [ 614.628914] Key type ._llcrypt registered [ 614.631579] Key type .llcrypt registered [ 616.743106] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 09:39:09 (1786801149) [ 619.593405] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 619.709764] alg: No test for adler32 (adler32-zlib) [ 620.769552] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 620.981271] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 622.656106] Key type lgssc registered [ 624.067398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 675.668397] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 683.026552] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 701.612620] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 710.788701] Lustre: Mounted lustre-client [ 713.303727] Lustre: Unmounted lustre-client [ 739.529411] Key type lgssc unregistered [ 739.778282] LNet: 18354:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 739.785244] LNetError: 18354:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 739.801492] LNet: Removed LNI 192.168.203.41@tcp [ 740.279623] Key type .llcrypt unregistered [ 740.284610] Key type ._llcrypt unregistered [ 755.305276] Key type ._llcrypt registered [ 755.306789] Key type .llcrypt registered [ 756.529588] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 09:41:30 (1786801290) [ 759.103534] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 759.120568] alg: No test for adler32 (adler32-zlib) [ 760.133564] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 760.388086] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 762.096135] Key type lgssc registered [ 764.079794] Lustre: Echo OBD driver; http://www.lustre.org/ [ 810.274813] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 815.283985] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 839.447165] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 842.237858] Lustre: Mounted lustre-client [ 850.883720] Lustre: Unmounted lustre-client [ 880.333774] Key type lgssc unregistered [ 880.564378] LNet: 22008:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 880.571982] LNetError: 22008:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 880.605383] LNet: Removed LNI 192.168.203.41@tcp [ 881.626269] Key type .llcrypt unregistered [ 881.639492] Key type ._llcrypt unregistered [ 898.114210] Key type ._llcrypt registered [ 898.116640] Key type .llcrypt registered [ 899.724757] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 09:43:53 (1786801433) [ 902.278494] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 902.312988] alg: No test for adler32 (adler32-zlib) [ 903.801861] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 904.205094] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 905.983421] Key type lgssc registered [ 907.091710] Lustre: Echo OBD driver; http://www.lustre.org/ [ 957.259785] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 964.938912] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 984.301169] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 987.048734] Lustre: Mounted lustre-client [ 994.617587] Lustre: Unmounted lustre-client [ 1025.925887] Key type lgssc unregistered [ 1026.365048] LNet: 25635:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1026.381934] LNetError: 25635:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1026.435891] LNet: Removed LNI 192.168.203.41@tcp [ 1027.466490] Key type .llcrypt unregistered [ 1027.469860] Key type ._llcrypt unregistered [ 1045.877152] Key type ._llcrypt registered [ 1045.881720] Key type .llcrypt registered [ 1047.161519] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 09:46:20 (1786801580) [ 1049.786056] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1049.815283] alg: No test for adler32 (adler32-zlib) [ 1050.992577] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 1051.208733] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 1053.017609] Key type lgssc registered [ 1054.299988] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1104.699659] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1110.680952] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1133.687972] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1135.532572] Lustre: Mounted lustre-client [ 1148.279540] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1158.655874] Lustre: Unmounted lustre-client [ 1183.060802] Key type lgssc unregistered [ 1183.293291] LNet: 29254:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1183.301025] LNetError: 29254:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1183.316320] LNet: Removed LNI 192.168.203.41@tcp [ 1183.980772] Key type .llcrypt unregistered [ 1183.982699] Key type ._llcrypt unregistered [ 1197.072731] Key type ._llcrypt registered [ 1197.078267] Key type .llcrypt registered [ 1198.456663] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 09:48:52 (1786801732) [ 1200.901706] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1200.931419] alg: No test for adler32 (adler32-zlib) [ 1202.030362] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 1202.210510] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 1203.880293] Key type lgssc registered [ 1204.869881] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1248.827879] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1254.463357] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1276.779165] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1278.211878] Lustre: Mounted lustre-client [ 1288.686779] Lustre: lustre-MDT0000-mdc-ffff9b3e11ee9000: Connection to lustre-MDT0000 (at 192.168.203.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1298.935744] Lustre: lustre-MDT0001-mdc-ffff9b3e11ee9000: Connection to lustre-MDT0001 (at 192.168.203.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1305.453561] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1310.175226] Lustre: 30213:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786801829/real 1786801829] req@0000000003786cc2 x1873597418182784/t0(0) o400->MGC192.168.203.141@tcp@192.168.203.141@tcp:26/25 lens 224/224 e 0 to 1 dl 1786801845 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1310.199139] LustreError: MGC192.168.203.141@tcp: Connection to MGS (at 192.168.203.141@tcp) was lost; in progress operations using this service will fail [ 1315.937308] Lustre: Unmounted lustre-client [ 1332.387675] Key type lgssc unregistered [ 1332.679656] LNet: 32905:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1332.697447] LNetError: 32905:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1332.751271] LNet: Removed LNI 192.168.203.41@tcp [ 1333.468855] Key type .llcrypt unregistered [ 1333.471437] Key type ._llcrypt unregistered [ 1348.240572] Key type ._llcrypt registered [ 1348.242117] Key type .llcrypt registered [ 1349.856843] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 09:51:23 (1786801883) [ 1351.793059] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1351.833122] alg: No test for adler32 (adler32-zlib) [ 1352.919506] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 1353.088521] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 1354.719713] Key type lgssc registered [ 1355.865714] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1440.462968] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1446.047954] LustreError: 35106:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.203.141@tcp: failed processing log, type 1: rc = -110 [ 1455.074525] LustreError: 35115:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1605.600373] LustreError: MGC192.168.203.141@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 1605.625270] Lustre: Unmounted lustre-client [ 1605.631122] LustreError: 35106:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1618.179974] Key type lgssc unregistered [ 1618.402643] LNet: 35532:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1618.409969] LNetError: 35532:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1618.435282] LNet: Removed LNI 192.168.203.41@tcp [ 1619.088423] Key type .llcrypt unregistered [ 1619.096345] Key type ._llcrypt unregistered [ 1633.885242] Key type ._llcrypt registered [ 1633.887480] Key type .llcrypt registered [ 1634.933816] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 09:56:08 (1786802168) [ 1637.787595] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1637.813402] alg: No test for adler32 (adler32-zlib) [ 1638.830091] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 1639.074404] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 1640.735181] Key type lgssc registered [ 1641.866034] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1690.835860] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1698.216556] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1719.589564] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1719.958618] LustreError: 38622:0:(llite_lib.c:1564:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1719.977769] Lustre: Unmounted wrong.lustre-client [ 1719.987901] LustreError: 38622:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1747.663862] Key type lgssc unregistered [ 1748.239966] LNet: 39039:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1748.253905] LNetError: 39039:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1748.286682] LNet: Removed LNI 192.168.203.41@tcp [ 1749.650313] Key type .llcrypt unregistered [ 1749.668597] Key type ._llcrypt unregistered [ 1774.134743] Key type ._llcrypt registered [ 1774.137970] Key type .llcrypt registered [ 1775.626852] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 09:58:29 (1786802309) [ 1777.801721] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1777.838100] alg: No test for adler32 (adler32-zlib) [ 1778.931089] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 1779.166524] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 1780.831272] Key type lgssc registered [ 1782.642205] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1868.392861] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1901.182971] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1908.585290] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1922.294357] Lustre: Mounted lustre-client [ 1926.418756] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1936.715537] Lustre: Unmounted lustre-client [ 1975.532049] Key type lgssc unregistered [ 1975.823617] LNet: 42630:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1975.831518] LNetError: 42630:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1975.844597] LNet: Removed LNI 192.168.203.41@tcp [ 1976.675206] Key type .llcrypt unregistered [ 1976.676867] Key type ._llcrypt unregistered [ 1992.857606] Key type ._llcrypt registered [ 1992.860758] Key type .llcrypt registered [ 1994.349795] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 10:02:07 (1786802527) [ 1996.749450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1996.782319] alg: No test for adler32 (adler32-zlib) [ 1997.719446] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 1998.076846] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 1999.815532] Key type lgssc registered [ 2000.909803] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2050.403567] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2055.958555] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2076.683800] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2077.284244] LustreError: 43589:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2082.403353] LustreError: 45713:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.203.141@tcp: failed processing log, type 1: rc = -110 [ 2087.303150] LustreError: 43589:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2088.542438] Lustre: Mounted lustre-client [ 2089.724353] Lustre: Unmounted lustre-client [ 2120.386830] Key type lgssc unregistered [ 2120.785857] LNet: 46170:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2120.792953] LNetError: 46170:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2120.811066] LNet: Removed LNI 192.168.203.41@tcp [ 2121.383612] Key type .llcrypt unregistered [ 2121.386756] Key type ._llcrypt unregistered [ 2138.192901] Key type ._llcrypt registered [ 2138.197516] Key type .llcrypt registered [ 2139.372344] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 10:04:33 (1786802673) [ 2140.905768] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2142.853524] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 10:04:36 (1786802676) [ 2143.952764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2143.975100] alg: No test for adler32 (adler32-zlib) [ 2145.100213] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 2145.386752] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 2147.063834] Key type lgssc registered [ 2148.034428] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2169.510689] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 10:05:03 (1786802703) [ 2205.122359] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2211.520698] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2230.942436] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2232.571173] Lustre: Mounted lustre-client [ 2243.046292] Lustre: lustre-MDT0000-mdc-ffff9b3e07ee3800: Connection to lustre-MDT0000 (at 192.168.203.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2258.425053] LustreError: MGC192.168.203.141@tcp: Connection to MGS (at 192.168.203.141@tcp) was lost; in progress operations using this service will fail [ 2258.465251] Lustre: Evicted from MGS (at 192.168.203.141@tcp) after server handle changed from 0x8a7829852c9be03c to 0x8a7829852c9be2c7 [ 2258.477927] Lustre: MGC192.168.203.141@tcp: Connection restored to 192.168.203.141@tcp (at 192.168.203.141@tcp) [ 2262.122529] Lustre: lustre-MDT0000-mdc-ffff9b3e07ee3800: Connection restored to 192.168.203.141@tcp (at 192.168.203.141@tcp) [ 2266.162188] Lustre: Unmounted lustre-client [ 2292.402168] Key type lgssc unregistered [ 2292.693369] LNet: 51022:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2292.703543] LNetError: 51022:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2292.725862] LNet: Removed LNI 192.168.203.41@tcp [ 2293.405736] Key type .llcrypt unregistered [ 2293.410613] Key type ._llcrypt unregistered [ 2309.763292] Key type ._llcrypt registered [ 2309.767626] Key type .llcrypt registered [ 2311.204704] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 10:07:24 (1786802844) [ 2313.687429] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2313.769116] alg: No test for adler32 (adler32-zlib) [ 2314.811484] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 2315.047595] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 2316.719196] Key type lgssc registered [ 2318.064867] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2373.460305] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2379.040410] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2406.257796] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2408.159774] Lustre: Mounted lustre-client [ 2413.551386] Lustre: lustre-MDT0000-mdc-ffff9b3e11ee9000: Connection to lustre-MDT0000 (at 192.168.203.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2428.896521] Lustre: 51982:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786802948/real 1786802948] req@00000000674309bd x1873598585245184/t0(0) o400->MGC192.168.203.141@tcp@192.168.203.141@tcp:26/25 lens 224/224 e 0 to 1 dl 1786802964 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2428.908780] LustreError: MGC192.168.203.141@tcp: Connection to MGS (at 192.168.203.141@tcp) was lost; in progress operations using this service will fail [ 2428.970673] Lustre: Evicted from MGS (at 192.168.203.141@tcp) after server handle changed from 0xbb93123147d8101b to 0xbb93123147d8129f [ 2428.984313] Lustre: MGC192.168.203.141@tcp: Connection restored to 192.168.203.141@tcp (at 192.168.203.141@tcp) [ 2433.118480] Lustre: lustre-MDT0000-mdc-ffff9b3e11ee9000: Connection restored to 192.168.203.141@tcp (at 192.168.203.141@tcp) [ 2437.309730] Lustre: Unmounted lustre-client [ 2467.044349] Key type lgssc unregistered [ 2467.266238] LNet: 54988:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2467.272956] LNetError: 54988:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2467.285832] LNet: Removed LNI 192.168.203.41@tcp [ 2467.846137] Key type .llcrypt unregistered [ 2467.848780] Key type ._llcrypt unregistered [ 2484.989509] Key type ._llcrypt registered [ 2484.993453] Key type .llcrypt registered [ 2486.219634] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 10:10:20 (1786803020) [ 2486.866414] Key type .llcrypt unregistered [ 2486.869289] Key type ._llcrypt unregistered [ 2493.425829] Key type ._llcrypt registered [ 2493.432710] Key type .llcrypt registered [ 2493.509577] Key type .llcrypt unregistered [ 2493.511648] Key type ._llcrypt unregistered [ 2498.608416] Key type ._llcrypt registered [ 2498.611265] Key type .llcrypt registered [ 2499.899230] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 10:10:33 (1786803033) [ 2502.307801] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2502.343911] alg: No test for adler32 (adler32-zlib) [ 2503.395951] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 2503.653623] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 2505.450491] Key type lgssc registered [ 2506.600354] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2554.155534] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2559.080838] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2578.895117] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2580.429831] Lustre: Mounted lustre-client [ 2582.319277] Lustre: Unmounted lustre-client [ 2582.635894] Lustre: Mounted lustre-client [ 2584.791619] Lustre: Unmounted lustre-client [ 2610.215335] Key type lgssc unregistered [ 2610.446393] LNet: 59409:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2610.451681] LNetError: 59409:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2610.466507] LNet: Removed LNI 192.168.203.41@tcp [ 2610.980102] Key type .llcrypt unregistered [ 2610.989887] Key type ._llcrypt unregistered [ 2625.030758] Key type ._llcrypt registered [ 2625.033706] Key type .llcrypt registered [ 2626.333205] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 10:12:40 (1786803160) [ 2628.493162] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2628.527095] alg: No test for adler32 (adler32-zlib) [ 2629.586127] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 2629.828653] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 2631.535840] Key type lgssc registered [ 2633.112481] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2683.490108] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2690.174215] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2710.042830] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2712.640932] Lustre: Mounted lustre-client [ 2714.796021] Lustre: Unmounted lustre-client [ 2741.734539] Key type lgssc unregistered [ 2742.062848] LNet: 62978:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2742.071513] LNetError: 62978:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2742.092416] LNet: Removed LNI 192.168.203.41@tcp [ 2742.860342] Key type .llcrypt unregistered [ 2742.871833] Key type ._llcrypt unregistered [ 2758.938544] Key type ._llcrypt registered [ 2758.940209] Key type .llcrypt registered [ 2761.364675] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 10:14:53 (1786803293) [ 2764.989545] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2765.089248] alg: No test for adler32 (adler32-zlib) [ 2766.599692] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 2766.948395] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 2768.631747] Key type lgssc registered [ 2770.399856] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2817.716315] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2824.434758] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2846.085168] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2849.029337] Lustre: Mounted lustre-client [ 2851.128962] Lustre: Mounted lustre-client [ 2853.356495] Lustre: Unmounted lustre-client [ 2855.145404] Lustre: Unmounted lustre-client [ 2879.495976] Key type lgssc unregistered [ 2879.685599] LNet: 66638:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2879.692402] LNetError: 66638:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2879.708405] LNet: Removed LNI 192.168.203.41@tcp [ 2880.350362] Key type .llcrypt unregistered [ 2880.352752] Key type ._llcrypt unregistered [ 2892.079486] Key type ._llcrypt registered [ 2892.081442] Key type .llcrypt registered [ 2893.040416] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 10:17:07 (1786803427) [ 2895.026172] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2895.055096] alg: No test for adler32 (adler32-zlib) [ 2896.102662] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 2896.375190] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 2898.015178] Key type lgssc registered [ 2898.930758] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2977.343440] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2993.508783] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 10:18:47 (1786803527) [ 3030.019801] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3035.978278] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3057.394743] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3059.062316] Lustre: Mounted lustre-client [ 3068.127455] Lustre: Unmounted lustre-client [ 3092.421490] Key type lgssc unregistered [ 3092.641966] LNet: 71400:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3092.648338] LNetError: 71400:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3092.666177] LNet: Removed LNI 192.168.203.41@tcp [ 3093.314858] Key type .llcrypt unregistered [ 3093.317275] Key type ._llcrypt unregistered [ 3105.683198] Key type ._llcrypt registered [ 3105.687799] Key type .llcrypt registered [ 3106.827927] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 10:20:40 (1786803640) [ 3121.257391] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 10:20:55 (1786803655) [ 3122.934559] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3122.951074] alg: No test for adler32 (adler32-zlib) [ 3123.948865] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 3124.148692] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 3125.775479] Key type lgssc registered [ 3126.827261] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3173.278045] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3179.639492] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3201.948608] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3205.211080] Lustre: Mounted lustre-client [ 3211.773122] Lustre: Unmounted lustre-client [ 3237.192584] Key type lgssc unregistered [ 3237.519092] LNet: 75683:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3237.523712] LNetError: 75683:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3237.547902] LNet: Removed LNI 192.168.203.41@tcp [ 3238.124935] Key type .llcrypt unregistered [ 3238.128041] Key type ._llcrypt unregistered [ 3249.561874] Key type ._llcrypt registered [ 3249.563365] Key type .llcrypt registered [ 3249.992408] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3250.003404] alg: No test for adler32 (adler32-zlib) [ 3251.168356] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 3251.480919] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 3253.199213] Key type lgssc registered [ 3254.988394] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3338.603375] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3459.070688] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3463.950433] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3481.423189] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3526.004111] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 10:27:40 (1786804060) [ 3528.916641] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3615.936631] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3623.215665] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3647.680263] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3716.318341] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3721.856867] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3742.486756] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3744.831674] Lustre: Mounted lustre-client [ 3749.410582] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 3751.062035] Lustre: Unmounted lustre-client [ 3776.970273] Key type lgssc unregistered [ 3777.260990] LNet: 87367:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3777.270729] LNetError: 87367:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3777.289625] LNet: Removed LNI 192.168.203.41@tcp [ 3778.173944] Key type .llcrypt unregistered [ 3778.176804] Key type ._llcrypt unregistered [ 3808.446789] Key type ._llcrypt registered [ 3808.454037] Key type .llcrypt registered [ 3809.004840] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3809.025150] alg: No test for adler32 (adler32-zlib) [ 3810.172265] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 3810.499389] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 3812.191350] Key type lgssc registered [ 3813.258812] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3891.027506] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3895.754364] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3916.559199] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3955.108865] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 10:34:49 (1786804489) [ 3984.942883] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3990.114875] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4009.664472] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 10:35:43 (1786804543) [ 4075.292756] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4084.882980] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 10:36:59 (1786804619) [ 4113.894116] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4119.430246] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4138.380639] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4140.559350] Lustre: Mounted lustre-client [ 4141.320562] Lustre: Remounted lustre-client read-only [ 4144.464603] Lustre: Unmounted lustre-client [ 4179.029118] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 10:38:32 (1786804712) [ 4209.962762] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4216.023964] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4236.311778] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4282.573468] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 10:40:16 (1786804816) [ 4353.993397] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4386.478146] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4391.811758] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4433.079186] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 10:42:47 (1786804967) [ 4498.281020] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4529.288902] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4535.325597] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4558.224457] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4619.206329] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 10:45:53 (1786805153) [ 4621.480954] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 4622.701893] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 4624.169025] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 10:45:58 (1786805158) [ 4656.244472] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4661.609636] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4680.973562] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4699.366543] Lustre: Mounted lustre-client [ 4700.500608] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4710.840688] Lustre: Unmounted lustre-client [ 4730.041236] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4730.712109] Lustre: Mounted lustre-client [ 4744.534784] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9b3e10c5f800.ost_server_uuid 50 [ 4747.028636] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9b3e10c5f800.ost_server_uuid in FULL state after 0 sec [ 4750.047562] Lustre: Unmounted lustre-client [ 4784.344607] Key type lgssc unregistered [ 4784.599419] LNet: 107730:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4784.606342] LNetError: 107730:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4784.623605] LNet: Removed LNI 192.168.203.41@tcp [ 4785.456772] Key type .llcrypt unregistered [ 4785.459335] Key type ._llcrypt unregistered [ 4801.444858] Key type ._llcrypt registered [ 4801.453058] Key type .llcrypt registered [ 4803.202874] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 10:48:56 (1786805336) [ 4805.604504] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4805.654685] alg: No test for adler32 (adler32-zlib) [ 4806.680062] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 4806.958339] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 4808.623819] Key type lgssc registered [ 4809.868099] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4856.754996] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4862.187602] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4881.920874] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4883.634137] Lustre: Mounted lustre-client [ 4889.095058] Lustre: lustre-MDT0000-mdc-ffff9b3e187e8800: Connection to lustre-MDT0000 (at 192.168.203.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4890.889369] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4901.203135] LustreError: MGC192.168.203.141@tcp: Connection to MGS (at 192.168.203.141@tcp) was lost; in progress operations using this service will fail [ 4901.248742] Lustre: Unmounted lustre-client [ 4925.331399] LustreError: lustre-MDT0000-mdc-ffff9b3e08042800: operation mds_connect to node 192.168.203.141@tcp failed: rc = -16 [ 4930.452888] LustreError: 111115:0:(lmv_obd.c:1468:lmv_statfs()) lustre-MDT0000-mdc-ffff9b3e08042800: can't stat MDS #0: rc = -16 [ 4930.540544] Lustre: Unmounted lustre-client [ 4930.565704] LustreError: 111115:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 4954.261962] Key type lgssc unregistered [ 4954.504046] LNet: 111566:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4954.512837] LNetError: 111566:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4954.532054] LNet: Removed LNI 192.168.203.41@tcp [ 4955.263205] Key type .llcrypt unregistered [ 4955.265071] Key type ._llcrypt unregistered [ 4967.556107] Key type ._llcrypt registered [ 4967.560150] Key type .llcrypt registered [ 4968.779249] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 10:51:42 (1786805502) [ 4970.734253] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4970.746758] alg: No test for adler32 (adler32-zlib) [ 4971.765528] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 4971.977665] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 4973.615394] Key type lgssc registered [ 4974.580459] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5019.008911] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5023.336918] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5042.159252] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5044.868257] Lustre: Mounted lustre-client [ 5046.272842] Lustre: Unmounted lustre-client [ 5073.261320] Key type lgssc unregistered [ 5073.514104] LNet: 115092:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5073.524139] LNetError: 115092:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5073.558642] LNet: Removed LNI 192.168.203.41@tcp [ 5074.121600] Key type .llcrypt unregistered [ 5074.124187] Key type ._llcrypt unregistered [ 5086.944662] Key type ._llcrypt registered [ 5086.950364] Key type .llcrypt registered [ 5087.968924] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 10:53:42 (1786805622) [ 5089.827419] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 5091.173964] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 10:53:45 (1786805625) [ 5093.175732] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 5094.461379] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 10:53:48 (1786805628) [ 5096.527764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5096.575896] alg: No test for adler32 (adler32-zlib) [ 5097.612095] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 5097.856257] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 5099.503182] Key type lgssc registered [ 5100.362347] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5143.384788] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5151.246560] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5172.950447] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5175.911062] Lustre: Mounted lustre-client [ 5179.437675] Lustre: Unmounted lustre-client [ 5211.423932] Key type lgssc unregistered [ 5211.667301] LNet: 119277:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5211.686580] LNetError: 119277:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5211.717758] LNet: Removed LNI 192.168.203.41@tcp [ 5212.398176] Key type .llcrypt unregistered [ 5212.402763] Key type ._llcrypt unregistered [ 5226.357454] Key type ._llcrypt registered [ 5226.360182] Key type .llcrypt registered [ 5227.982539] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 10:56:01 (1786805761) [ 5229.100843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5229.119892] alg: No test for adler32 (adler32-zlib) [ 5230.184910] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 5230.457485] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 5232.127196] Key type lgssc registered [ 5232.900154] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5279.932834] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5285.460588] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5303.840555] Key type lgssc unregistered [ 5304.053603] LNet: 122354:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5304.066922] LNetError: 122354:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5304.087022] LNet: Removed LNI 192.168.203.41@tcp [ 5304.709734] Key type .llcrypt unregistered [ 5304.712799] Key type ._llcrypt unregistered [ 5320.514900] Key type ._llcrypt registered [ 5320.516632] Key type .llcrypt registered [ 5321.735150] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 10:57:35 (1786805855) [ 5330.931239] Key type .llcrypt unregistered [ 5330.936645] Key type ._llcrypt unregistered [ 5338.926364] Key type ._llcrypt registered [ 5338.934809] Key type .llcrypt registered [ 5339.426540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5339.453505] alg: No test for adler32 (adler32-zlib) [ 5340.591587] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 5340.927602] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 5342.671275] Key type lgssc registered [ 5344.011453] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5428.933625] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5462.884211] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5468.824694] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5504.921855] Key type lgssc unregistered [ 5505.138389] LNet: 126208:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5505.143681] LNetError: 126208:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5505.156255] LNet: Removed LNI 192.168.203.41@tcp [ 5505.861658] Key type .llcrypt unregistered [ 5505.866313] Key type ._llcrypt unregistered [ 5521.048458] Key type ._llcrypt registered [ 5521.050865] Key type .llcrypt registered [ 5522.300947] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 11:00:56 (1786806056) [ 5524.872310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5524.890674] alg: No test for adler32 (adler32-zlib) [ 5526.226896] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 5526.561806] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 5528.296045] Key type lgssc registered [ 5529.382816] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5578.627240] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5584.378657] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5603.822505] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5606.658659] Lustre: Mounted lustre-client [ 5612.010106] Lustre: lustre-MDT0000-mdc-ffff9b3e07ee2800: Connection to lustre-MDT0000 (at 192.168.203.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5628.191189] Lustre: 127193:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786806147/real 1786806147] req@000000009cdebf98 x1873601952222336/t0(0) o400->MGC192.168.203.141@tcp@192.168.203.141@tcp:26/25 lens 224/224 e 0 to 1 dl 1786806163 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5628.235855] LustreError: MGC192.168.203.141@tcp: Connection to MGS (at 192.168.203.141@tcp) was lost; in progress operations using this service will fail [ 5638.448326] Lustre: Evicted from MGS (at 192.168.203.141@tcp) after server handle changed from 0xd45f27f6ef91dfb3 to 0xd45f27f6ef91e007 [ 5638.457359] Lustre: MGC192.168.203.141@tcp: Connection restored to 192.168.203.141@tcp (at 192.168.203.141@tcp) [ 5644.357647] Lustre: lustre-MDT0000-mdc-ffff9b3e07ee2800: Connection restored to 192.168.203.141@tcp (at 192.168.203.141@tcp) [ 5666.718636] Lustre: Unmounted lustre-client [ 5697.161368] Key type lgssc unregistered [ 5697.431774] LNet: 130221:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5697.447409] LNetError: 130221:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5697.473510] LNet: Removed LNI 192.168.203.41@tcp [ 5698.330407] Key type .llcrypt unregistered [ 5698.333470] Key type ._llcrypt unregistered [ 5713.368769] Key type ._llcrypt registered [ 5713.371558] Key type .llcrypt registered [ 5714.626420] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 11:04:08 (1786806248) [ 5717.371781] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5717.410263] alg: No test for adler32 (adler32-zlib) [ 5718.376667] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 5718.648464] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 5720.306432] Key type lgssc registered [ 5721.441234] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5767.492792] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5772.178342] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5792.857570] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5795.601441] Lustre: Mounted lustre-client [ 5812.384806] Lustre: Unmounted lustre-client [ 5812.776613] LustreError: 131195:0:(lmv_obd.c:208:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 5812.827310] Lustre: Mounted lustre-client [ 5821.760699] Lustre: Unmounted lustre-client [ 5850.020756] Key type lgssc unregistered [ 5850.488706] LNet: 134408:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5850.499199] LNetError: 134408:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5850.524729] LNet: Removed LNI 192.168.203.41@tcp [ 5851.620379] Key type .llcrypt unregistered [ 5851.622763] Key type ._llcrypt unregistered [ 5864.870923] Key type ._llcrypt registered [ 5864.872990] Key type .llcrypt registered [ 5865.826205] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 11:06:39 (1786806399) [ 5868.939579] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5868.980233] alg: No test for adler32 (adler32-zlib) [ 5870.130390] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 5870.472995] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 5872.208190] Key type lgssc registered [ 5873.422050] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5920.680743] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5926.062700] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5943.894495] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5945.364757] Lustre: Mounted lustre-client [ 5979.992279] Lustre: Unmounted lustre-client [ 6007.437703] Key type lgssc unregistered [ 6007.694485] LNet: 137776:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6007.705898] LNetError: 137776:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6007.731532] LNet: Removed LNI 192.168.203.41@tcp [ 6008.376821] Key type .llcrypt unregistered [ 6008.379236] Key type ._llcrypt unregistered [ 6021.206182] Key type ._llcrypt registered [ 6021.208313] Key type .llcrypt registered [ 6022.478925] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 11:09:16 (1786806556) [ 6024.520930] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6024.599047] alg: No test for adler32 (adler32-zlib) [ 6025.641966] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 6025.828799] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 6027.519221] Key type lgssc registered [ 6028.329815] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6071.310856] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6076.849371] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6097.584853] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6100.496810] Lustre: Mounted lustre-client [ 6102.882363] Lustre: Unmounted lustre-client [ 6103.397572] Lustre: Mounted lustre-client [ 6109.921023] Lustre: Unmounted lustre-client [ 6141.794143] Key type lgssc unregistered [ 6142.311078] LNet: 141818:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6142.325075] LNetError: 141818:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6142.359963] LNet: Removed LNI 192.168.203.41@tcp [ 6143.483565] Key type .llcrypt unregistered [ 6143.494640] Key type ._llcrypt unregistered [ 6155.203031] Key type ._llcrypt registered [ 6155.205119] Key type .llcrypt registered [ 6156.322866] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 11:11:30 (1786806690) [ 6158.720320] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6158.739746] alg: No test for adler32 (adler32-zlib) [ 6160.054229] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 6160.503223] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 6162.199636] Key type lgssc registered [ 6163.706995] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6223.118669] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6227.429094] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6248.155354] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6250.867694] Lustre: Mounted lustre-client [ 6252.081812] Lustre: Unmounted lustre-client [ 6252.458562] Lustre: Mounted lustre-client [ 6259.242887] Lustre: Unmounted lustre-client [ 6294.881428] Key type lgssc unregistered [ 6295.115898] LNet: 145441:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6295.122370] LNetError: 145441:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6295.146390] LNet: Removed LNI 192.168.203.41@tcp [ 6295.927365] Key type .llcrypt unregistered [ 6295.931222] Key type ._llcrypt unregistered [ 6305.696796] Key type ._llcrypt registered [ 6305.700345] Key type .llcrypt registered [ 6306.888715] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 11:14:00 (1786806840) [ 6309.393856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6309.411427] alg: No test for adler32 (adler32-zlib) [ 6310.448644] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 6310.645268] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 6312.295305] Key type lgssc registered [ 6313.383183] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6359.102420] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6364.768358] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6384.293435] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6386.991538] Lustre: Mounted lustre-client [ 6407.159857] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6408.764995] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6429.214413] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6432.631081] Lustre: Unmounted lustre-client [ 6433.038831] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6433.258639] Lustre: Mounted lustre-client [ 6444.044923] Lustre: Unmounted lustre-client [ 6487.646600] Key type lgssc unregistered [ 6487.934858] LNet: 149496:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6487.948841] LNetError: 149496:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6487.962552] LNet: Removed LNI 192.168.203.41@tcp [ 6488.658398] Key type .llcrypt unregistered [ 6488.661437] Key type ._llcrypt unregistered [ 6513.925582] Key type ._llcrypt registered [ 6513.927379] Key type .llcrypt registered [ 6515.073716] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 11:17:29 (1786807049) [ 6517.544713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6517.593090] alg: No test for adler32 (adler32-zlib) [ 6518.637978] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 6518.837559] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 6520.480346] Key type lgssc registered [ 6521.490326] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6563.738970] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6568.649616] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6586.625568] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6588.210312] Lustre: Mounted lustre-client [ 6750.637792] Lustre: Unmounted lustre-client [ 6751.033497] Lustre: Mounted lustre-client [ 6753.852511] Lustre: Unmounted lustre-client [ 6754.305555] Lustre: Mounted lustre-client [ 6756.242128] Lustre: Unmounted lustre-client [ 6783.866800] Key type lgssc unregistered [ 6784.150946] LNet: 154766:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6784.166787] LNetError: 154766:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6784.193380] LNet: Removed LNI 192.168.203.41@tcp [ 6784.800535] Key type .llcrypt unregistered [ 6784.803498] Key type ._llcrypt unregistered [ 6799.183953] Key type ._llcrypt registered [ 6799.185973] Key type .llcrypt registered [ 6800.125172] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 11:22:14 (1786807334) [ 6801.979445] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6802.006503] alg: No test for adler32 (adler32-zlib) [ 6803.135376] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 6803.398346] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 6805.063203] Key type lgssc registered [ 6806.042688] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6850.659381] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6855.841693] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6874.436538] Lustre: DEBUG MARKER: oleg341-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6877.036380] Lustre: Mounted lustre-client [ 6883.516744] Lustre: Unmounted lustre-client [ 6883.970367] Lustre: Mounted lustre-client [ 6885.935596] Lustre: Unmounted lustre-client [ 6915.975956] Key type lgssc unregistered [ 6916.214859] LNet: 158462:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6916.220257] LNetError: 158462:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6916.237375] LNet: Removed LNI 192.168.203.41@tcp [ 6916.761565] Key type .llcrypt unregistered [ 6916.763359] Key type ._llcrypt unregistered [ 6929.439519] Key type ._llcrypt registered [ 6929.441109] Key type .llcrypt registered [ 6930.349265] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 11:24:24 (1786807464) [ 6931.228319] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6931.252173] alg: No test for adler32 (adler32-zlib) [ 6932.408184] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 6932.673365] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 6934.351631] Key type lgssc registered [ 6935.365834] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6956.448250] LustreError: 160155:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 6963.167800] LustreError: 160164:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 7011.551612] LNet: 1 local NIs in recovery (showing 1): 192.168.203.41@tcp [ 7083.231420] LNet: 1 local NIs in recovery (showing 1): 192.168.203.41@tcp [ 7114.721361] LustreError: MGC4.3.2.1@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 7114.746925] Lustre: Unmounted lustre-client [ 7114.788653] LustreError: 160155:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 7128.836727] Key type lgssc unregistered [ 7139.424197] LNet: 160573:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7139.441425] LNetError: 160573:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7214.251338] LNet: Removed LNI 192.168.203.41@tcp [ 7215.002910] Key type .llcrypt unregistered [ 7215.012274] Key type ._llcrypt unregistered [ 7258.149335] Key type ._llcrypt registered [ 7258.156411] Key type .llcrypt registered [ 7258.495807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7258.523271] alg: No test for adler32 (adler32-zlib) [ 7259.686746] Lustre: Lustre: Build Version: 2.17.56_1_gfa464be [ 7259.964528] LNet: Added LNI 192.168.203.41@tcp [8/256/0/180] [ 7261.903317] Key type lgssc registered [ 7263.253116] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7310.031747] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7029 sec ========== 11:30:43 (1786807843) [ 7311.604454] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 11:30:45 (1786807845) === [ 7314.633974] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 11:30:48 (1786807848) === [ 7346.425265] Key type lgssc unregistered [ 7346.633508] LNet: 164810:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7346.638596] LNetError: 164810:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7346.647502] LNet: Removed LNI 192.168.203.41@tcp [ 7347.260698] Key type .llcrypt unregistered [ 7347.262874] Key type ._llcrypt unregistered