[ 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 458248682 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, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.003148] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005012] kvm-guest: setup PV IPIs [ 0.007484] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008019] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009013] pid_max: default: 32768 minimum: 301 [ 0.011094] LSM: Security Framework initializing [ 0.012054] Yama: becoming mindful. [ 0.013033] SELinux: Initializing. [ 0.014055] *** VALIDATE selinux *** [ 0.022346] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026254] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027126] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028079] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029089] *** VALIDATE tmpfs *** [ 0.030350] *** VALIDATE proc *** [ 0.031189] *** VALIDATE cgroup *** [ 0.032010] *** VALIDATE cgroup2 *** [ 0.033264] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034130] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036022] Spectre V2 : User space: Vulnerable [ 0.037009] Speculative Store Bypass: Vulnerable [ 0.040131] debug: unmapping init [mem 0xffffffff8b259000-0xffffffff8b260fff] [ 0.043188] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044725] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045027] ... version: 2 [ 0.046011] ... bit width: 48 [ 0.047009] ... generic registers: 4 [ 0.048000] ... value mask: 0000ffffffffffff [ 0.049018] ... max period: 00007fffffffffff [ 0.050012] ... fixed-purpose events: 3 [ 0.051013] ... event mask: 000000070000000f [ 0.052328] rcu: Hierarchical SRCU implementation. [ 0.054337] smp: Bringing up secondary CPUs ... [ 0.055531] x86: Booting SMP configuration: [ 0.056019] .... node #0, CPUs: #1 #2 #3 [ 0.059070] smp: Brought up 1 node, 4 CPUs [ 0.061015] smpboot: Max logical packages: 1 [ 0.062019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.137855] node 0 deferred pages initialised in 73ms [ 0.140093] devtmpfs: initialized [ 0.141214] x86/mm: Memory block size: 128MB [ 0.144000] gcov: version magic: 0x41383552 [ 0.148021] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.149084] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.150247] pinctrl core: initialized pinctrl subsystem [ 0.151227] [ 0.151595] ************************************************************* [ 0.152019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.153014] ** ** [ 0.154016] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.155015] ** ** [ 0.156017] ** This means that this kernel is built to expose internal ** [ 0.157014] ** IOMMU data structures, which may compromise security on ** [ 0.158011] ** your system. ** [ 0.159015] ** ** [ 0.160011] ** If you see this message and you are not debugging the ** [ 0.161011] ** kernel, report this immediately to your vendor! ** [ 0.162013] ** ** [ 0.163014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.164010] ************************************************************* [ 0.165746] NET: Registered protocol family 16 [ 0.167401] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.170060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.173069] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.177041] cpuidle: using governor menu [ 0.179057] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.182643] PCI: Using configuration type 1 for base access [ 0.185172] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.196191] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.199115] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.203095] cryptd: max_cpu_qlen set to 1000 [ 0.206166] ACPI: Added _OSI(Module Device) [ 0.208024] ACPI: Added _OSI(Processor Device) [ 0.210019] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.211021] ACPI: Added _OSI(Processor Aggregator Device) [ 0.216281] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.222632] ACPI: Interpreter enabled [ 0.224098] ACPI: PM: (supports S0 S3 S4 S5) [ 0.226018] ACPI: Using IOAPIC for interrupt routing [ 0.228168] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.232417] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.243593] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.246117] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.250023] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.253082] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.259644] acpiphp: Slot [2] registered [ 0.261188] acpiphp: Slot [5] registered [ 0.263192] acpiphp: Slot [6] registered [ 0.265219] acpiphp: Slot [3] registered [ 0.267109] acpiphp: Slot [4] registered [ 0.269107] acpiphp: Slot [7] registered [ 0.270109] acpiphp: Slot [8] registered [ 0.272123] acpiphp: Slot [9] registered [ 0.274163] acpiphp: Slot [10] registered [ 0.275152] acpiphp: Slot [11] registered [ 0.276100] acpiphp: Slot [12] registered [ 0.278100] acpiphp: Slot [13] registered [ 0.279041] acpiphp: Slot [14] registered [ 0.280024] acpiphp: Slot [15] registered [ 0.281104] acpiphp: Slot [16] registered [ 0.282116] acpiphp: Slot [17] registered [ 0.283086] acpiphp: Slot [18] registered [ 0.284100] acpiphp: Slot [19] registered [ 0.286190] acpiphp: Slot [20] registered [ 0.288127] acpiphp: Slot [21] registered [ 0.289135] acpiphp: Slot [22] registered [ 0.291172] acpiphp: Slot [23] registered [ 0.292117] acpiphp: Slot [24] registered [ 0.294123] acpiphp: Slot [25] registered [ 0.296121] acpiphp: Slot [26] registered [ 0.297117] acpiphp: Slot [27] registered [ 0.299101] acpiphp: Slot [28] registered [ 0.300109] acpiphp: Slot [29] registered [ 0.302108] acpiphp: Slot [30] registered [ 0.304162] acpiphp: Slot [31] registered [ 0.305073] PCI host bridge to bus 0000:00 [ 0.307026] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.310035] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.313043] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.315032] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.318043] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.322041] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.324206] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.327220] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.330517] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.338580] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.343015] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.346029] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.348024] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.351039] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.354347] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.357869] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.360050] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.364077] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.369015] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.379018] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.384021] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.390438] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.395019] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.400022] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.412028] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.426785] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.438016] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.446020] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.467019] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.482135] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.484576] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.487568] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.490391] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.494262] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.500228] iommu: Default domain type: Passthrough [ 0.502445] SCSI subsystem initialized [ 0.504146] ACPI: bus type USB registered [ 0.506190] usbcore: registered new interface driver usbfs [ 0.508123] usbcore: registered new interface driver hub [ 0.510107] usbcore: registered new device driver usb [ 0.512168] pps_core: LinuxPPS API ver. 1 registered [ 0.515013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.518063] PTP clock support registered [ 0.521088] EDAC MC: Ver: 3.0.0 [ 0.523146] PCI: Using ACPI for IRQ routing [ 0.525834] NetLabel: Initializing [ 0.527014] NetLabel: domain hash size = 128 [ 0.529012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.531090] NetLabel: unlabeled traffic allowed by default [ 0.534086] vgaarb: loaded [ 0.536298] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.538019] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.548964] clocksource: Switched to clocksource kvm-clock [ 0.652058] VFS: Disk quotas dquot_6.6.0 [ 0.654125] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.658186] *** VALIDATE ramfs *** [ 0.659637] *** VALIDATE hugetlbfs *** [ 0.661468] pnp: PnP ACPI init [ 0.664109] pnp: PnP ACPI: found 6 devices [ 0.684159] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.687266] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.688797] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.690270] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.692082] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.693749] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.695835] NET: Registered protocol family 2 [ 0.697581] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.701024] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.703516] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.707698] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.710920] TCP: Hash tables configured (established 65536 bind 65536) [ 0.713512] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.716146] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.718547] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.720935] NET: Registered protocol family 1 [ 0.723277] RPC: Registered named UNIX socket transport module. [ 0.724993] RPC: Registered udp transport module. [ 0.726390] RPC: Registered tcp transport module. [ 0.727606] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.729403] NET: Registered protocol family 44 [ 0.730610] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.732183] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.733721] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.735629] PCI: CLS 0 bytes, default 64 [ 0.738458] Unpacking initramfs... [ 2.163196] debug: unmapping init [mem 0xffff8fc8bcc64000-0xffff8fc8bffcffff] [ 2.165822] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.167277] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.169232] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.623780] Initialise system trusted keyrings [ 2.625485] Key type blacklist registered [ 2.627434] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.636596] zbud: loaded [ 2.639589] *** VALIDATE nfs *** [ 2.640512] *** VALIDATE nfs4 *** [ 2.642285] pstore: using deflate compression [ 2.645633] Platform Keyring initialized [ 2.729657] NET: Registered protocol family 38 [ 2.731229] Key type asymmetric registered [ 2.732427] Asymmetric key parser 'x509' registered [ 2.734052] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.736834] io scheduler mq-deadline registered [ 2.738345] io scheduler kyber registered [ 2.739608] io scheduler bfq registered [ 2.742800] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.745368] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.748088] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.750433] ACPI: Power Button [PWRF] [ 2.754661] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.760571] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.769150] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.795458] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.823451] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.828120] Non-volatile memory driver v1.3 [ 2.830255] Linux agpgart interface v0.103 [ 2.862190] virtio_blk virtio1: [vda] 146144 512-byte logical blocks (74.8 MB/71.4 MiB) [ 2.864365] vda: detected capacity change from 0 to 74825728 [ 2.880172] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.883136] vdb: detected capacity change from 0 to 1073741824 [ 2.889211] libphy: Fixed MDIO Bus: probed [ 2.893928] usbcore: registered new interface driver usbserial_generic [ 2.895824] usbserial: USB Serial support registered for generic [ 2.897633] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.901595] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.903954] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.906542] mousedev: PS/2 mouse device common for all mice [ 2.909959] rtc_cmos 00:05: RTC can wake from S4 [ 2.912437] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.916586] rtc_cmos 00:05: registered as rtc0 [ 2.918421] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.922451] intel_pstate: CPU model not supported [ 2.925420] hid: raw HID events driver (C) Jiri Kosina [ 2.927393] usbcore: registered new interface driver usbhid [ 2.929396] usbhid: USB HID core driver [ 2.931251] drop_monitor: Initializing network drop monitor service [ 2.931703] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.933323] Initializing XFRM netlink socket [ 2.937348] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.937453] NET: Registered protocol family 10 [ 2.942468] Segment Routing with IPv6 [ 2.943562] NET: Registered protocol family 17 [ 2.945069] mpls_gso: MPLS GSO support [ 2.949800] RAS: Correctable Errors collector initialized. [ 2.951874] AVX version of gcm_enc/dec engaged. [ 2.953563] AES CTR mode by8 optimization enabled [ 3.046937] sched_clock: Marking stable (3046868783, 0)->(3955616995, -908748212) [ 3.049888] registered taskstats version 1 [ 3.051686] Loading compiled-in X.509 certificates [ 3.054565] zswap: loaded using pool lzo/zbud [ 3.077538] Key type big_key registered [ 3.089589] Key type encrypted registered [ 3.091558] ima: No TPM chip found, activating TPM-bypass! [ 3.094684] ima: Allocated hash algorithm: sha1 [ 3.096630] ima: No architecture policies found [ 3.098621] evm: Initialising EVM extended attributes: [ 3.100893] evm: security.selinux [ 3.102301] evm: security.ima [ 3.103595] evm: security.capability [ 3.105186] evm: HMAC attrs: 0x1 [ 3.107986] rtc_cmos 00:05: setting system clock to 2026-08-26 01:23:22 UTC (1787707402) [ 3.114949] debug: unmapping init [mem 0xffffffff8c203000-0xffffffff8c3fffff] [ 3.118515] debug: unmapping init [mem 0xffffffff8af82000-0xffffffff8b258fff] [ 3.129101] Write protecting the kernel read-only data: 28672k [ 3.133242] debug: unmapping init [mem 0xffffffff89603000-0xffffffff897fffff] [ 3.136548] debug: unmapping init [mem 0xffffffff89f14000-0xffffffff89ffffff] [ 3.168550] 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.174901] systemd[1]: Detected virtualization kvm. [ 3.176392] systemd[1]: Detected architecture x86-64. [ 3.177873] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.200809] systemd[1]: No hostname configured. [ 3.203443] systemd[1]: Set hostname to . [ 3.204871] random: systemd: uninitialized urandom read (16 bytes read) [ 3.206790] systemd[1]: Initializing machine ID from random generator. [ 3.255829] random: ln: uninitialized urandom read (6 bytes read) [ 3.347543] random: systemd: uninitialized urandom read (16 bytes read) [ 3.350377] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.354643] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.358483] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Slices. [ OK ] Reached target Timers. Starting Apply Kernel Variables... [ OK ] Reached target Initrd Root Device. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.061480] device-mapper: uevent: version 1.0.3 [ 4.063829] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.854431] virtio_net virtio0 ens2: renamed from eth0 [ 6.156605] scsi host0: ata_piix [ 6.230895] scsi host1: ata_piix [ 6.233595] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.247263] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 11.625227] random: crng init done [ 11.627576] random: 7 urandom warning(s) missed due to ratelimiting [ 12.799700] dracut-initqueue[586]: RTNETLINK answers: File exists 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... [ 15.160649] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ 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... [ 18.630384] printk: systemd: 22 output lines suppressed due to ratelimiting [ 19.630287] SELinux: Disabled at runtime. [ 19.743452] 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) [ 19.759813] systemd[1]: Detected virtualization kvm. [ 19.763556] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 21.791921] systemd[1]: initrd-switch-root.service: Succeeded. [ 21.799479] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 21.815407] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 21.821210] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 21.825765] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 21.845581] systemd[1]: Starting Journal Service... Starting Journal Service... [ 21.865995] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. Mounting Kernel Debug File System... [ OK ] Created slice system-serial\x2dgetty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-sshd\x2dkeygen.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting Huge Pages File System... [ 22.129707] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Initrd File Systems. Starting Apply Kernel Variables... [ OK ] Reached target RPC Port Mapper. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on Process Core Dump Socket. Mounting POSIX Message Queue File System... [ OK ] Created slice system-getty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Remount Root and Kernel File Systems... Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ 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 ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [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... [ 22.967096] hrtimer: interrupt took 4087037 ns [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 23.543596] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 24.749293] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 25.041889] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 25.508896] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 25.600490] EDAC sbridge: Ver: 1.1.2 [ 29.449825] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit)[ 30.209388] NFS: Registering the id_resolver key type [ 30.212080] Key type id_resolver registered [ 30.213323] Key type id_legacy registered [*** ] A start job is running for Configur…-only root support (8s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ OK ] Started Configure read-only root support. [ 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... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg230-client login: [ 111.099531] libcfs: loading out-of-tree module taints kernel. [ 111.706354] Key type ._llcrypt registered [ 111.708280] Key type .llcrypt registered [ 112.261284] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 112.272778] alg: No test for adler32 (adler32-zlib) [ 114.071637] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 115.205297] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 117.152194] Key type lgssc registered [ 119.152094] Lustre: Echo OBD driver; http://www.lustre.org/ [ 308.991891] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 315.409176] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 332.181167] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing check_logdir /tmp/testlogs/ [ 334.816226] Lustre: lustre-OST0000-osc-ffff8fc912a19000: disconnect after 23s idle [ 337.994482] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing yml_node [ 343.823349] Lustre: DEBUG MARKER: Client: 2.17.57.80 [ 347.216656] Lustre: DEBUG MARKER: MDS: 2.17.57.80 [ 350.589474] Lustre: DEBUG MARKER: OSS: 2.17.57.80 [ 352.548078] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Aug 25 21:29:10 EDT 2026 [ 371.333855] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 373.678908] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 377.787352] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 388.134145] Lustre: Unmounted lustre-client [ 496.232520] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 502.203238] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 525.332955] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 562.970302] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 21:32:41 (1787707961) [ 595.389545] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 601.167891] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 621.425430] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 622.131375] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 635.228820] Lustre: Unmounted lustre-client [ 665.845578] Key type lgssc unregistered [ 666.127977] LNet: 14704:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 666.138925] LNetError: 14704:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 666.169188] LNet: Removed LNI 192.168.202.30@tcp [ 667.279183] Key type .llcrypt unregistered [ 667.281266] Key type ._llcrypt unregistered [ 684.937640] Key type ._llcrypt registered [ 684.941783] Key type .llcrypt registered [ 686.664835] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 21:34:44 (1787708084) [ 689.469097] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 689.479350] alg: No test for adler32 (adler32-zlib) [ 690.545913] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 690.766109] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 692.456203] Key type lgssc registered [ 693.588964] Lustre: Echo OBD driver; http://www.lustre.org/ [ 750.965852] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 758.531659] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 780.733683] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 790.691080] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 793.558828] Lustre: Unmounted lustre-client [ 817.463037] Key type lgssc unregistered [ 817.728851] LNet: 18385:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 817.736551] LNetError: 18385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 817.752263] LNet: Removed LNI 192.168.202.30@tcp [ 818.480671] Key type .llcrypt unregistered [ 818.484428] Key type ._llcrypt unregistered [ 835.489887] Key type ._llcrypt registered [ 835.497042] Key type .llcrypt registered [ 837.157319] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 21:37:14 (1787708234) [ 839.500598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 839.521242] alg: No test for adler32 (adler32-zlib) [ 840.652213] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 840.986627] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 842.704629] Key type lgssc registered [ 843.667763] Lustre: Echo OBD driver; http://www.lustre.org/ [ 891.591220] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 898.490931] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 924.830864] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 926.381559] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 929.198151] Lustre: Unmounted lustre-client [ 960.718417] Key type lgssc unregistered [ 961.117309] LNet: 22042:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 961.124407] LNetError: 22042:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 961.148956] LNet: Removed LNI 192.168.202.30@tcp [ 961.942173] Key type .llcrypt unregistered [ 961.948348] Key type ._llcrypt unregistered [ 980.317286] Key type ._llcrypt registered [ 980.319233] Key type .llcrypt registered [ 981.613175] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 21:39:39 (1787708379) [ 984.740061] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 984.757365] alg: No test for adler32 (adler32-zlib) [ 986.207874] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 986.560914] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 988.344191] Key type lgssc registered [ 990.352460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1045.066336] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1051.468053] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1071.064970] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1073.755870] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 1082.109390] Lustre: Unmounted lustre-client [ 1113.879954] Key type lgssc unregistered [ 1114.119642] LNet: 25661:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1114.130466] LNetError: 25661:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1114.150191] LNet: Removed LNI 192.168.202.30@tcp [ 1114.746991] Key type .llcrypt unregistered [ 1114.750202] Key type ._llcrypt unregistered [ 1130.451637] Key type ._llcrypt registered [ 1130.453683] Key type .llcrypt registered [ 1131.751066] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 21:42:09 (1787708529) [ 1133.971128] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1134.010164] alg: No test for adler32 (adler32-zlib) [ 1135.066573] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 1135.347657] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 1137.040200] Key type lgssc registered [ 1138.467665] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1195.573667] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1202.808971] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1226.899774] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1238.044881] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 1248.255743] Lustre: lustre-OST0000-osc-ffff8fc90512f800: Connection to lustre-OST0000 (at 192.168.202.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1255.363790] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1265.709754] Lustre: Unmounted lustre-client [ 1302.523684] Key type lgssc unregistered [ 1302.787471] LNet: 29286:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1302.794259] LNetError: 29286:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1302.812075] LNet: Removed LNI 192.168.202.30@tcp [ 1303.526658] Key type .llcrypt unregistered [ 1303.529283] Key type ._llcrypt unregistered [ 1319.728670] Key type ._llcrypt registered [ 1319.731414] Key type .llcrypt registered [ 1321.224978] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 21:45:18 (1787708718) [ 1323.449828] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1323.591566] alg: No test for adler32 (adler32-zlib) [ 1324.686630] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 1324.909862] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 1326.536221] Key type lgssc registered [ 1327.475935] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1385.611915] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1391.881954] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1414.418278] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1417.357582] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 1422.337035] Lustre: lustre-MDT0000-mdc-ffff8fc902f67000: Connection to lustre-MDT0000 (at 192.168.202.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1432.551441] Lustre: lustre-MDT0001-mdc-ffff8fc902f67000: Connection to lustre-MDT0001 (at 192.168.202.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1439.604966] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1447.915434] Lustre: 30244:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787708831/real 1787708831] req@000000001e4f707a x1874548462983296/t0(0) o400->MGC192.168.202.130@tcp@192.168.202.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1787708847 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1447.964869] LustreError: MGC192.168.202.130@tcp: Connection to MGS (at 192.168.202.130@tcp) was lost; in progress operations using this service will fail [ 1450.016202] Lustre: Unmounted lustre-client [ 1469.303642] Key type lgssc unregistered [ 1469.574025] LNet: 32948:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1469.586646] LNetError: 32948:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1469.603777] LNet: Removed LNI 192.168.202.30@tcp [ 1470.367604] Key type .llcrypt unregistered [ 1470.369936] Key type ._llcrypt unregistered [ 1488.091842] Key type ._llcrypt registered [ 1488.094725] Key type .llcrypt registered [ 1489.407824] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 21:48:07 (1787708887) [ 1491.865745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1491.911808] alg: No test for adler32 (adler32-zlib) [ 1493.066360] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 1493.278843] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 1494.936232] Key type lgssc registered [ 1496.443790] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1579.848789] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1585.505067] LustreError: 35149:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.202.130@tcp: failed processing log, type 1: rc = -110 [ 1594.848690] LustreError: 35158:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1744.864838] LustreError: MGC192.168.202.130@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 [ 1744.885281] Lustre: Unmounted lustre-client [ 1744.895809] LustreError: 35149:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1759.158652] Key type lgssc unregistered [ 1759.480098] LNet: 35579:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1759.486444] LNetError: 35579:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1759.502512] LNet: Removed LNI 192.168.202.30@tcp [ 1760.124279] Key type .llcrypt unregistered [ 1760.128467] Key type ._llcrypt unregistered [ 1775.897348] Key type ._llcrypt registered [ 1775.899911] Key type .llcrypt registered [ 1777.266961] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 21:52:55 (1787709175) [ 1780.183339] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1780.230772] alg: No test for adler32 (adler32-zlib) [ 1781.519042] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 1781.944446] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 1783.688946] Key type lgssc registered [ 1784.913119] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1840.036903] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1845.281784] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1866.353356] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1867.028556] LustreError: 38672:0:(llite_lib.c:1555:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1867.047821] Lustre: Unmounted wrong.lustre-client [ 1867.065284] LustreError: 38672:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1895.630617] Key type lgssc unregistered [ 1895.908077] LNet: 39094:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1895.919534] LNetError: 39094:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1895.945315] LNet: Removed LNI 192.168.202.30@tcp [ 1896.860056] Key type .llcrypt unregistered [ 1896.861851] Key type ._llcrypt unregistered [ 1913.633602] Key type ._llcrypt registered [ 1913.637051] Key type .llcrypt registered [ 1915.371065] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 21:55:12 (1787709312) [ 1917.844300] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1918.015328] alg: No test for adler32 (adler32-zlib) [ 1919.064357] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 1919.307959] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 1921.016463] Key type lgssc registered [ 1922.380422] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2010.178309] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2046.823416] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2052.980281] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2064.902187] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 2067.096756] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2077.436988] Lustre: Unmounted lustre-client [ 2108.151894] Key type lgssc unregistered [ 2108.445649] LNet: 42669:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2108.452555] LNetError: 42669:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2108.462549] LNet: Removed LNI 192.168.202.30@tcp [ 2109.092497] Key type .llcrypt unregistered [ 2109.096277] Key type ._llcrypt unregistered [ 2124.917260] Key type ._llcrypt registered [ 2124.922735] Key type .llcrypt registered [ 2126.435471] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 21:58:44 (1787709524) [ 2128.822960] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2128.846497] alg: No test for adler32 (adler32-zlib) [ 2130.087934] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 2130.448204] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 2132.192782] Key type lgssc registered [ 2133.643394] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2187.321309] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2193.157457] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2217.097285] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2218.785667] LustreError: 43631:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2222.880971] LustreError: 45757:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.202.130@tcp: failed processing log, type 1: rc = -110 [ 2228.808177] LustreError: 43631:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2230.165225] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 2232.818738] Lustre: Unmounted lustre-client [ 2269.624381] Key type lgssc unregistered [ 2270.046832] LNet: 46232:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2270.064369] LNetError: 46232:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2270.090781] LNet: Removed LNI 192.168.202.30@tcp [ 2270.745484] Key type .llcrypt unregistered [ 2270.748491] Key type ._llcrypt unregistered [ 2288.811537] Key type ._llcrypt registered [ 2288.814174] Key type .llcrypt registered [ 2290.847262] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 22:01:27 (1787709687) [ 2293.270785] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2295.099392] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 22:01:33 (1787709693) [ 2296.711220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2296.721438] alg: No test for adler32 (adler32-zlib) [ 2298.011039] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 2298.296373] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 2300.008195] Key type lgssc registered [ 2301.694693] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2331.113509] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 22:02:08 (1787709728) [ 2368.650451] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2375.042445] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2399.068281] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2400.811081] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 2411.013892] Lustre: lustre-MDT0000-mdc-ffff8fc9108b7800: Connection to lustre-MDT0000 (at 192.168.202.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2426.397518] LustreError: MGC192.168.202.130@tcp: Connection to MGS (at 192.168.202.130@tcp) was lost; in progress operations using this service will fail [ 2426.441712] Lustre: Evicted from MGS (at 192.168.202.130@tcp) after server handle changed from 0x90493e915b63545c to 0x90493e915b635551 [ 2426.450117] Lustre: MGC192.168.202.130@tcp: Connection restored to 192.168.202.130@tcp (at 192.168.202.130@tcp) [ 2433.986784] Lustre: lustre-MDT0000-mdc-ffff8fc9108b7800: Connection restored to 192.168.202.130@tcp (at 192.168.202.130@tcp) [ 2439.602909] Lustre: Unmounted lustre-client [ 2476.212210] Key type lgssc unregistered [ 2476.436911] LNet: 51096:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2476.442702] LNetError: 51096:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2476.476239] LNet: Removed LNI 192.168.202.30@tcp [ 2477.454066] Key type .llcrypt unregistered [ 2477.462147] Key type ._llcrypt unregistered [ 2496.332244] Key type ._llcrypt registered [ 2496.334609] Key type .llcrypt registered [ 2498.247316] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 22:04:55 (1787709895) [ 2500.585978] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2500.816272] alg: No test for adler32 (adler32-zlib) [ 2501.952128] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 2502.333549] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 2504.088197] Key type lgssc registered [ 2505.191873] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2555.879942] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2561.782239] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2582.552621] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2585.440672] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 2590.707154] Lustre: lustre-MDT0000-mdc-ffff8fc90512d800: Connection to lustre-MDT0000 (at 192.168.202.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2607.072114] Lustre: 52058:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787709990/real 1787709990] req@0000000026864662 x1874549697154688/t0(0) o400->MGC192.168.202.130@tcp@192.168.202.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1787710006 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2607.092862] LustreError: MGC192.168.202.130@tcp: Connection to MGS (at 192.168.202.130@tcp) was lost; in progress operations using this service will fail [ 2613.777614] Lustre: lustre-MDT0000-mdc-ffff8fc90512d800: Connection restored to 192.168.202.130@tcp (at 192.168.202.130@tcp) [ 2616.883991] Lustre: Evicted from MGS (at 192.168.202.130@tcp) after server handle changed from 0x6067a83ed89a4a2c to 0x6067a83ed89a4dd6 [ 2616.893486] Lustre: MGC192.168.202.130@tcp: Connection restored to 192.168.202.130@tcp (at 192.168.202.130@tcp) [ 2618.715552] Lustre: Unmounted lustre-client [ 2651.195767] Key type lgssc unregistered [ 2651.453515] LNet: 55061:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2651.458808] LNetError: 55061:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2651.477314] LNet: Removed LNI 192.168.202.30@tcp [ 2652.146817] Key type .llcrypt unregistered [ 2652.149575] Key type ._llcrypt unregistered [ 2670.868345] Key type ._llcrypt registered [ 2670.870355] Key type .llcrypt registered [ 2672.032101] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 22:07:50 (1787710070) [ 2672.634419] Key type .llcrypt unregistered [ 2672.648957] Key type ._llcrypt unregistered [ 2679.896629] Key type ._llcrypt registered [ 2679.898741] Key type .llcrypt registered [ 2679.944578] Key type .llcrypt unregistered [ 2679.952035] Key type ._llcrypt unregistered [ 2685.118883] Key type ._llcrypt registered [ 2685.128812] Key type .llcrypt registered [ 2686.554050] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 22:08:04 (1787710084) [ 2689.403410] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2689.586494] alg: No test for adler32 (adler32-zlib) [ 2690.755918] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 2691.038228] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 2692.768259] Key type lgssc registered [ 2694.026469] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2743.816906] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2750.115492] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2769.181228] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2771.976490] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 2777.380586] Lustre: Unmounted lustre-client [ 2777.783781] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 2781.392942] Lustre: Unmounted lustre-client [ 2818.989781] Key type lgssc unregistered [ 2819.281420] LNet: 59486:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2819.294276] LNetError: 59486:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2819.316218] LNet: Removed LNI 192.168.202.30@tcp [ 2820.398264] Key type .llcrypt unregistered [ 2820.403796] Key type ._llcrypt unregistered [ 2839.496118] Key type ._llcrypt registered [ 2839.506081] Key type .llcrypt registered [ 2841.345412] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 22:10:38 (1787710238) [ 2844.057680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2844.067375] alg: No test for adler32 (adler32-zlib) [ 2845.264263] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 2845.536775] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 2847.194940] Key type lgssc registered [ 2848.319423] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2910.955749] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2917.580518] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2946.686612] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2948.439245] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 2951.197751] Lustre: Unmounted lustre-client [ 2979.390331] Key type lgssc unregistered [ 2979.629746] LNet: 63060:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2979.640254] LNetError: 63060:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2979.659837] LNet: Removed LNI 192.168.202.30@tcp [ 2980.311193] Key type .llcrypt unregistered [ 2980.315341] Key type ._llcrypt unregistered [ 2997.445747] Key type ._llcrypt registered [ 2997.452255] Key type .llcrypt registered [ 2998.992872] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 22:13:16 (1787710396) [ 3001.932362] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3001.997353] alg: No test for adler32 (adler32-zlib) [ 3003.176438] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 3003.348808] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 3005.009625] Key type lgssc registered [ 3006.246116] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3051.869243] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3057.792665] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3081.921097] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3084.768145] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 3087.430475] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 3090.343809] Lustre: Unmounted lustre-client [ 3093.231510] Lustre: Unmounted lustre-client [ 3129.885435] Key type lgssc unregistered [ 3130.125221] LNet: 66718:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3130.131241] LNetError: 66718:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3130.143557] LNet: Removed LNI 192.168.202.30@tcp [ 3130.797600] Key type .llcrypt unregistered [ 3130.799865] Key type ._llcrypt unregistered [ 3146.506988] Key type ._llcrypt registered [ 3146.513876] Key type .llcrypt registered [ 3147.741948] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 22:15:45 (1787710545) [ 3149.921110] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3149.952435] alg: No test for adler32 (adler32-zlib) [ 3151.070812] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 3151.287220] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 3152.968182] Key type lgssc registered [ 3154.269526] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3236.902479] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3250.558892] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 22:17:28 (1787710648) [ 3279.337920] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3284.652604] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3308.015574] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3311.266473] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 3320.084957] Lustre: Unmounted lustre-client [ 3347.482584] Key type lgssc unregistered [ 3347.719180] LNet: 71483:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3347.726948] LNetError: 71483:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3347.748948] LNet: Removed LNI 192.168.202.30@tcp [ 3348.488279] Key type .llcrypt unregistered [ 3348.492871] Key type ._llcrypt unregistered [ 3364.334536] Key type ._llcrypt registered [ 3364.336951] Key type .llcrypt registered [ 3365.933615] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 22:19:23 (1787710763) [ 3383.277883] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 22:19:41 (1787710781) [ 3385.368175] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3385.423937] alg: No test for adler32 (adler32-zlib) [ 3386.524785] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 3386.714156] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 3388.432187] Key type lgssc registered [ 3389.797648] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3440.041885] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3447.312607] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3471.305168] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3472.987333] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 3478.169965] Lustre: Unmounted lustre-client [ 3507.991069] Key type lgssc unregistered [ 3508.348022] LNet: 75785:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3508.355188] LNetError: 75785:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3508.372337] LNet: Removed LNI 192.168.202.30@tcp [ 3509.298889] Key type .llcrypt unregistered [ 3509.300650] Key type ._llcrypt unregistered [ 3524.048884] Key type ._llcrypt registered [ 3524.063790] Key type .llcrypt registered [ 3524.851088] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3524.877736] alg: No test for adler32 (adler32-zlib) [ 3526.241556] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 3526.738995] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 3528.544820] Key type lgssc registered [ 3530.500413] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3620.793534] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3746.685841] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3752.540059] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3779.580458] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3821.743377] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 22:26:59 (1787711219) [ 3825.501252] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3913.855561] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3919.502453] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3943.881271] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4010.310698] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4016.873433] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4042.681968] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4044.630365] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 4050.578792] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 4053.065164] Lustre: Unmounted lustre-client [ 4096.158545] Key type lgssc unregistered [ 4096.407684] LNet: 87478:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4096.412326] LNetError: 87478:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4096.428105] LNet: Removed LNI 192.168.202.30@tcp [ 4097.184699] Key type .llcrypt unregistered [ 4097.188401] Key type ._llcrypt unregistered [ 4127.526327] Key type ._llcrypt registered [ 4127.529157] Key type .llcrypt registered [ 4128.126570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4128.135195] alg: No test for adler32 (adler32-zlib) [ 4129.424448] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 4129.708067] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 4131.360288] Key type lgssc registered [ 4132.823539] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4215.694478] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4220.786080] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4244.041541] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4284.958140] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 22:34:43 (1787711683) [ 4316.366915] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4321.455456] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4342.899596] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 22:35:40 (1787711740) [ 4411.796474] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4423.080937] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 22:37:00 (1787711820) [ 4457.893274] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4465.057937] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4487.332476] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4488.969874] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 4494.915368] Lustre: Remounted lustre-client read-only [ 4497.211332] Lustre: Unmounted lustre-client [ 4545.432989] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 22:39:03 (1787711943) [ 4581.087922] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4586.534154] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4605.609417] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4658.610358] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 22:40:56 (1787712056) [ 4726.520195] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4759.776383] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4764.688745] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4824.557768] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 22:43:42 (1787712222) [ 4895.346574] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4930.661118] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4937.231523] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4960.491917] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5042.995225] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 22:47:20 (1787712440) [ 5044.776336] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 5046.952880] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 5048.498786] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 22:47:26 (1787712446) [ 5081.967981] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5088.401479] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5114.949973] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5138.236325] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 5141.224812] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5151.561196] Lustre: Unmounted lustre-client [ 5174.863354] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5175.561653] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 5193.825263] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8fc91132a800.ost_server_uuid 50 [ 5195.403529] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8fc91132a800.ost_server_uuid in FULL state after 0 sec [ 5198.380143] Lustre: Unmounted lustre-client [ 5234.175519] Key type lgssc unregistered [ 5234.506932] LNet: 107861:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5234.518857] LNetError: 107861:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5234.537845] LNet: Removed LNI 192.168.202.30@tcp [ 5235.248483] Key type .llcrypt unregistered [ 5235.250703] Key type ._llcrypt unregistered [ 5251.712814] Key type ._llcrypt registered [ 5251.720091] Key type .llcrypt registered [ 5253.237216] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 22:50:50 (1787712650) [ 5255.518983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5255.545881] alg: No test for adler32 (adler32-zlib) [ 5256.650936] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 5256.907342] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 5258.585710] Key type lgssc registered [ 5259.638256] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5311.058760] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5316.396821] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5339.537952] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5341.250155] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 5350.839561] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5361.157378] LustreError: MGC192.168.202.130@tcp: Connection to MGS (at 192.168.202.130@tcp) was lost; in progress operations using this service will fail [ 5361.196329] Lustre: Unmounted lustre-client [ 5376.871800] LustreError: lustre-MDT0000-mdc-ffff8fc906860000: operation mds_connect to node 192.168.202.130@tcp failed: rc = -16 [ 5381.964440] LustreError: 111249:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8fc906860000: can't stat MDS #0: rc = -16 [ 5382.048663] Lustre: Unmounted lustre-client [ 5382.060311] LustreError: 111249:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 5407.505776] Key type lgssc unregistered [ 5407.760699] LNet: 111703:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5407.773702] LNetError: 111703:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5407.795830] LNet: Removed LNI 192.168.202.30@tcp [ 5408.691810] Key type .llcrypt unregistered [ 5408.695817] Key type ._llcrypt unregistered [ 5423.483819] Key type ._llcrypt registered [ 5423.489582] Key type .llcrypt registered [ 5424.745291] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 22:53:42 (1787712822) [ 5427.195269] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5427.238073] alg: No test for adler32 (adler32-zlib) [ 5428.345532] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 5428.676535] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 5430.377940] Key type lgssc registered [ 5431.982360] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5484.937988] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5490.453273] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5512.887642] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5514.612253] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 5517.254236] Lustre: Unmounted lustre-client [ 5545.776995] Key type lgssc unregistered [ 5546.018139] LNet: 115238:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5546.044454] LNetError: 115238:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5546.085400] LNet: Removed LNI 192.168.202.30@tcp [ 5546.927365] Key type .llcrypt unregistered [ 5546.929127] Key type ._llcrypt unregistered [ 5564.475732] Key type ._llcrypt registered [ 5564.477424] Key type .llcrypt registered [ 5565.873400] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 22:56:03 (1787712963) [ 5568.419944] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 5570.524195] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 22:56:08 (1787712968) [ 5573.855516] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 5576.198459] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 22:56:13 (1787712973) [ 5578.841624] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5578.868373] alg: No test for adler32 (adler32-zlib) [ 5579.996928] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 5580.302718] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 5582.000407] Key type lgssc registered [ 5583.471075] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5639.694740] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5646.287767] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5669.553589] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5672.296554] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 5677.034616] Lustre: Unmounted lustre-client [ 5708.045966] Key type lgssc unregistered [ 5708.434263] LNet: 119436:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5708.447331] LNetError: 119436:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5708.461931] LNet: Removed LNI 192.168.202.30@tcp [ 5709.485430] Key type .llcrypt unregistered [ 5709.490460] Key type ._llcrypt unregistered [ 5726.822561] Key type ._llcrypt registered [ 5726.824296] Key type .llcrypt registered [ 5728.798245] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 22:58:46 (1787713126) [ 5729.933389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5729.960532] alg: No test for adler32 (adler32-zlib) [ 5731.079813] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 5731.289092] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 5732.928281] Key type lgssc registered [ 5734.086355] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5794.434156] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5801.303789] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5822.738402] Key type lgssc unregistered [ 5823.133601] LNet: 122526:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5823.138790] LNetError: 122526:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5823.156780] LNet: Removed LNI 192.168.202.30@tcp [ 5823.998827] Key type .llcrypt unregistered [ 5824.001579] Key type ._llcrypt unregistered [ 5841.263582] Key type ._llcrypt registered [ 5841.267728] Key type .llcrypt registered [ 5842.611424] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 23:00:40 (1787713240) [ 5853.694445] Key type .llcrypt unregistered [ 5853.697533] Key type ._llcrypt unregistered [ 5863.281542] Key type ._llcrypt registered [ 5863.286209] Key type .llcrypt registered [ 5863.979991] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5863.994237] alg: No test for adler32 (adler32-zlib) [ 5865.061989] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 5865.216767] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 5866.841377] Key type lgssc registered [ 5868.280601] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5954.486361] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5988.585416] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5995.503431] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6035.610284] Key type lgssc unregistered [ 6035.879256] LNet: 126417:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6035.886707] LNetError: 126417:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6035.913308] LNet: Removed LNI 192.168.202.30@tcp [ 6036.685509] Key type .llcrypt unregistered [ 6036.687239] Key type ._llcrypt unregistered [ 6051.278587] Key type ._llcrypt registered [ 6051.282785] Key type .llcrypt registered [ 6052.455281] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 23:04:10 (1787713450) [ 6055.226577] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6055.247137] alg: No test for adler32 (adler32-zlib) [ 6056.323703] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 6056.641088] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 6058.328224] Key type lgssc registered [ 6059.488440] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6117.122058] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6122.856920] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6144.281773] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6156.360597] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 6161.403467] Lustre: lustre-MDT0000-mdc-ffff8fc906c7b800: Connection to lustre-MDT0000 (at 192.168.202.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6177.569318] Lustre: 127394:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787713560/real 1787713560] req@00000000f296fd64 x1874553424842496/t0(0) o400->MGC192.168.202.130@tcp@192.168.202.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1787713576 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6177.618028] LustreError: MGC192.168.202.130@tcp: Connection to MGS (at 192.168.202.130@tcp) was lost; in progress operations using this service will fail [ 6183.363057] Lustre: lustre-MDT0000-mdc-ffff8fc906c7b800: Connection restored to 192.168.202.130@tcp (at 192.168.202.130@tcp) [ 6187.885640] Lustre: Evicted from MGS (at 192.168.202.130@tcp) after server handle changed from 0x804bc9cea0d0544b to 0x804bc9cea0d05818 [ 6187.916758] Lustre: MGC192.168.202.130@tcp: Connection restored to 192.168.202.130@tcp (at 192.168.202.130@tcp) [ 6205.750328] Lustre: Unmounted lustre-client [ 6231.320236] Key type lgssc unregistered [ 6231.558812] LNet: 130439:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6231.566943] LNetError: 130439:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6231.587954] LNet: Removed LNI 192.168.202.30@tcp [ 6232.200904] Key type .llcrypt unregistered [ 6232.205222] Key type ._llcrypt unregistered [ 6249.347360] Key type ._llcrypt registered [ 6249.349784] Key type .llcrypt registered [ 6250.633544] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 23:07:28 (1787713648) [ 6252.795256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6252.979750] alg: No test for adler32 (adler32-zlib) [ 6254.014942] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 6254.204489] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 6255.858602] Key type lgssc registered [ 6256.944744] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6303.641419] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6308.810318] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6329.321284] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6332.090059] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 6349.160450] Lustre: Unmounted lustre-client [ 6349.856607] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 6360.517533] Lustre: Unmounted lustre-client [ 6394.299604] Key type lgssc unregistered [ 6394.716314] LNet: 134639:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6394.731844] LNetError: 134639:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6394.773334] LNet: Removed LNI 192.168.202.30@tcp [ 6395.711522] Key type .llcrypt unregistered [ 6395.713594] Key type ._llcrypt unregistered [ 6408.437657] Key type ._llcrypt registered [ 6408.443060] Key type .llcrypt registered [ 6409.645424] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 23:10:07 (1787713807) [ 6412.097426] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6412.233472] alg: No test for adler32 (adler32-zlib) [ 6413.196900] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 6413.360434] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 6415.032193] Key type lgssc registered [ 6416.048411] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6470.078621] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6475.277265] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6494.021967] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6495.636596] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 6527.332743] Lustre: Unmounted lustre-client [ 6552.843713] Key type lgssc unregistered [ 6553.042906] LNet: 138016:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6553.047120] LNetError: 138016:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6553.062306] LNet: Removed LNI 192.168.202.30@tcp [ 6553.684146] Key type .llcrypt unregistered [ 6553.687423] Key type ._llcrypt unregistered [ 6568.736981] Key type ._llcrypt registered [ 6568.739534] Key type .llcrypt registered [ 6569.882544] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 23:12:47 (1787713967) [ 6572.727476] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6572.753246] alg: No test for adler32 (adler32-zlib) [ 6573.894863] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 6574.119930] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 6575.769493] Key type lgssc registered [ 6576.944705] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6623.676332] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6629.143822] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6648.977060] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6651.842718] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 6654.022996] Lustre: Unmounted lustre-client [ 6654.352656] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 6660.877489] Lustre: Unmounted lustre-client [ 6696.878420] Key type lgssc unregistered [ 6697.109972] LNet: 142065:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6697.116393] LNetError: 142065:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6697.135263] LNet: Removed LNI 192.168.202.30@tcp [ 6697.992106] Key type .llcrypt unregistered [ 6697.993410] Key type ._llcrypt unregistered [ 6711.959815] Key type ._llcrypt registered [ 6711.965757] Key type .llcrypt registered [ 6713.568593] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 23:15:11 (1787714111) [ 6716.223882] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6716.389838] alg: No test for adler32 (adler32-zlib) [ 6717.723026] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 6717.999897] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 6719.669151] Key type lgssc registered [ 6720.861724] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6769.136796] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6775.709471] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6799.562280] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6802.885839] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 6806.273540] Lustre: Unmounted lustre-client [ 6806.878688] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 6814.542646] Lustre: Unmounted lustre-client [ 6851.200783] Key type lgssc unregistered [ 6851.448120] LNet: 145694:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6851.454272] LNetError: 145694:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6851.473291] LNet: Removed LNI 192.168.202.30@tcp [ 6852.225211] Key type .llcrypt unregistered [ 6852.228455] Key type ._llcrypt unregistered [ 6863.732514] Key type ._llcrypt registered [ 6863.734356] Key type .llcrypt registered [ 6865.324292] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 23:17:42 (1787714262) [ 6867.354384] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6867.408270] alg: No test for adler32 (adler32-zlib) [ 6868.439231] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 6868.688598] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 6870.426716] Key type lgssc registered [ 6871.887782] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6926.901761] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6932.896211] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6955.739373] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6957.504667] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 6976.977097] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6978.364677] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7000.075521] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7004.651612] Lustre: Unmounted lustre-client [ 7004.891932] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7004.969894] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 7016.311621] Lustre: Unmounted lustre-client [ 7048.889542] Key type lgssc unregistered [ 7049.108702] LNet: 149755:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7049.119323] LNetError: 149755:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7049.137951] LNet: Removed LNI 192.168.202.30@tcp [ 7049.921251] Key type .llcrypt unregistered [ 7049.924294] Key type ._llcrypt unregistered [ 7084.631488] Key type ._llcrypt registered [ 7084.633498] Key type .llcrypt registered [ 7086.214078] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 23:21:23 (1787714483) [ 7089.098578] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7089.186572] alg: No test for adler32 (adler32-zlib) [ 7090.398411] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 7090.752291] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 7092.496689] Key type lgssc registered [ 7093.833594] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7140.410446] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7146.142548] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7165.815204] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7168.668350] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 7327.986770] Lustre: Unmounted lustre-client [ 7328.479637] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 7330.961612] Lustre: Unmounted lustre-client [ 7331.325355] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 7332.558664] Lustre: Unmounted lustre-client [ 7365.353135] Key type lgssc unregistered [ 7365.700267] LNet: 155003:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7365.708151] LNetError: 155003:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7365.744421] LNet: Removed LNI 192.168.202.30@tcp [ 7366.300644] Key type .llcrypt unregistered [ 7366.306060] Key type ._llcrypt unregistered [ 7379.630643] Key type ._llcrypt registered [ 7379.632230] Key type .llcrypt registered [ 7380.740569] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 23:26:18 (1787714778) [ 7382.813373] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7382.832728] alg: No test for adler32 (adler32-zlib) [ 7383.893672] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 7384.077396] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 7385.712272] Key type lgssc registered [ 7386.874677] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7434.276295] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7438.997631] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7460.171123] Lustre: DEBUG MARKER: oleg230-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7462.116844] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 7471.711297] Lustre: Unmounted lustre-client [ 7472.167880] Lustre: Mounted lustre-client - version 2.17.57_80_gf69c3ef [ 7473.978122] Lustre: Unmounted lustre-client [ 7503.915824] Key type lgssc unregistered [ 7504.264207] LNet: 158708:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7504.280947] LNetError: 158708:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7504.306910] LNet: Removed LNI 192.168.202.30@tcp [ 7505.083825] Key type .llcrypt unregistered [ 7505.088952] Key type ._llcrypt unregistered [ 7521.820211] Key type ._llcrypt registered [ 7521.822424] Key type .llcrypt registered [ 7523.264615] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 23:28:41 (1787714921) [ 7524.415594] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7524.446893] alg: No test for adler32 (adler32-zlib) [ 7525.562101] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 7525.752619] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 7527.441724] Key type lgssc registered [ 7529.152559] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7554.400945] LustreError: 160403:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 7561.697091] LustreError: 160412:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 7603.680255] LNet: 1 local NIs in recovery (showing 1): 192.168.202.30@tcp [ 7681.504312] LNet: 1 local NIs in recovery (showing 1): 192.168.202.30@tcp [ 7712.736883] 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 [ 7712.771994] Lustre: Unmounted lustre-client [ 7712.784474] LustreError: 160403:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 7724.967561] Key type lgssc unregistered [ 7732.527532] LNet: 160820:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7732.552807] LNetError: 160820:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7812.455951] LNet: Removed LNI 192.168.202.30@tcp [ 7813.235532] Key type .llcrypt unregistered [ 7813.237948] Key type ._llcrypt unregistered [ 7843.355918] Key type ._llcrypt registered [ 7843.358873] Key type .llcrypt registered [ 7843.512282] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7843.524484] alg: No test for adler32 (adler32-zlib) [ 7844.526072] Lustre: Lustre: Build Version: 2.17.57_80_gf69c3ef [ 7844.713652] LNet: Added LNI 192.168.202.30@tcp [8/256/0/180] [ 7846.376309] Key type lgssc registered [ 7847.631491] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7892.400792] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7538 sec ========== 23:34:50 (1787715290) [ 7894.029466] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 23:34:52 (1787715292) === [ 7896.838769] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 23:34:55 (1787715295) === [ 7932.644504] Key type lgssc unregistered [ 7932.939430] LNet: 165064:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7932.951895] LNetError: 165064:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7932.967177] LNet: Removed LNI 192.168.202.30@tcp [ 7933.795825] Key type .llcrypt unregistered [ 7933.798558] Key type ._llcrypt unregistered