[ 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 425804872 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.002374] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.005009] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009015] pid_max: default: 32768 minimum: 301 [ 0.010141] LSM: Security Framework initializing [ 0.011058] Yama: becoming mindful. [ 0.012040] SELinux: Initializing. [ 0.013069] *** VALIDATE selinux *** [ 0.021232] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025837] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027082] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028108] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029116] *** VALIDATE tmpfs *** [ 0.031252] *** VALIDATE proc *** [ 0.032241] *** VALIDATE cgroup *** [ 0.033010] *** VALIDATE cgroup2 *** [ 0.034259] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035150] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037031] Spectre V2 : User space: Vulnerable [ 0.038008] Speculative Store Bypass: Vulnerable [ 0.041052] debug: unmapping init [mem 0xffffffffb1a59000-0xffffffffb1a60fff] [ 0.043891] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044713] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045027] ... version: 2 [ 0.046010] ... bit width: 48 [ 0.047013] ... generic registers: 4 [ 0.048013] ... value mask: 0000ffffffffffff [ 0.049015] ... max period: 00007fffffffffff [ 0.050015] ... fixed-purpose events: 3 [ 0.051011] ... event mask: 000000070000000f [ 0.052321] rcu: Hierarchical SRCU implementation. [ 0.054627] smp: Bringing up secondary CPUs ... [ 0.055595] x86: Booting SMP configuration: [ 0.056021] .... node #0, CPUs: #1 #2 #3 [ 0.059077] smp: Brought up 1 node, 4 CPUs [ 0.061009] smpboot: Max logical packages: 1 [ 0.062016] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.136012] node 0 deferred pages initialised in 72ms [ 0.139122] devtmpfs: initialized [ 0.140258] x86/mm: Memory block size: 128MB [ 0.144175] gcov: version magic: 0x41383552 [ 0.147297] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.151106] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.154665] pinctrl core: initialized pinctrl subsystem [ 0.156235] [ 0.156727] ************************************************************* [ 0.159012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.161011] ** ** [ 0.163013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.166012] ** ** [ 0.168009] ** This means that this kernel is built to expose internal ** [ 0.170011] ** IOMMU data structures, which may compromise security on ** [ 0.172007] ** your system. ** [ 0.173007] ** ** [ 0.175013] ** If you see this message and you are not debugging the ** [ 0.178016] ** kernel, report this immediately to your vendor! ** [ 0.180010] ** ** [ 0.182077] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.184010] ************************************************************* [ 0.186757] NET: Registered protocol family 16 [ 0.188476] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.191076] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.194075] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.197617] cpuidle: using governor menu [ 0.198786] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.199514] PCI: Using configuration type 1 for base access [ 0.200163] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.207203] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.210033] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.213336] cryptd: max_cpu_qlen set to 1000 [ 0.216237] ACPI: Added _OSI(Module Device) [ 0.217013] ACPI: Added _OSI(Processor Device) [ 0.219013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.221012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.226462] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.231588] ACPI: Interpreter enabled [ 0.234074] ACPI: PM: (supports S0 S3 S4 S5) [ 0.235075] ACPI: Using IOAPIC for interrupt routing [ 0.237097] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.240436] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.253913] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.257054] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.260031] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.263072] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.267394] acpiphp: Slot [2] registered [ 0.269203] acpiphp: Slot [5] registered [ 0.270133] acpiphp: Slot [6] registered [ 0.272144] acpiphp: Slot [3] registered [ 0.274203] acpiphp: Slot [4] registered [ 0.275110] acpiphp: Slot [7] registered [ 0.277104] acpiphp: Slot [8] registered [ 0.278080] acpiphp: Slot [9] registered [ 0.280104] acpiphp: Slot [10] registered [ 0.281098] acpiphp: Slot [11] registered [ 0.283138] acpiphp: Slot [12] registered [ 0.284089] acpiphp: Slot [13] registered [ 0.285130] acpiphp: Slot [14] registered [ 0.287091] acpiphp: Slot [15] registered [ 0.289106] acpiphp: Slot [16] registered [ 0.290100] acpiphp: Slot [17] registered [ 0.292099] acpiphp: Slot [18] registered [ 0.293135] acpiphp: Slot [19] registered [ 0.295091] acpiphp: Slot [20] registered [ 0.296067] acpiphp: Slot [21] registered [ 0.297102] acpiphp: Slot [22] registered [ 0.299097] acpiphp: Slot [23] registered [ 0.300094] acpiphp: Slot [24] registered [ 0.302126] acpiphp: Slot [25] registered [ 0.303097] acpiphp: Slot [26] registered [ 0.305096] acpiphp: Slot [27] registered [ 0.306102] acpiphp: Slot [28] registered [ 0.308123] acpiphp: Slot [29] registered [ 0.309120] acpiphp: Slot [30] registered [ 0.311113] acpiphp: Slot [31] registered [ 0.312073] PCI host bridge to bus 0000:00 [ 0.314019] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.316023] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.319024] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.321017] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.323026] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.327014] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.328174] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.332025] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.335196] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.343013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.347050] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.350017] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.352015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.355019] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.357858] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.361082] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.363035] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.366744] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.371014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.381016] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.385015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.390311] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.396015] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.403020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.432018] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.448138] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.455019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.461018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.478018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.487913] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.490409] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.493389] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.496418] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.498232] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.504115] iommu: Default domain type: Passthrough [ 0.506422] SCSI subsystem initialized [ 0.508135] ACPI: bus type USB registered [ 0.509107] usbcore: registered new interface driver usbfs [ 0.511065] usbcore: registered new interface driver hub [ 0.513086] usbcore: registered new device driver usb [ 0.515167] pps_core: LinuxPPS API ver. 1 registered [ 0.516010] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.520073] PTP clock support registered [ 0.522077] EDAC MC: Ver: 3.0.0 [ 0.523118] PCI: Using ACPI for IRQ routing [ 0.525822] NetLabel: Initializing [ 0.526009] NetLabel: domain hash size = 128 [ 0.527008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.529080] NetLabel: unlabeled traffic allowed by default [ 0.532092] vgaarb: loaded [ 0.533235] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.534015] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.542316] clocksource: Switched to clocksource kvm-clock [ 0.649416] VFS: Disk quotas dquot_6.6.0 [ 0.651434] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.654253] *** VALIDATE ramfs *** [ 0.655813] *** VALIDATE hugetlbfs *** [ 0.657461] pnp: PnP ACPI init [ 0.660245] pnp: PnP ACPI: found 6 devices [ 0.694825] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.697965] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.700213] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.702110] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.704407] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.706968] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.709887] NET: Registered protocol family 2 [ 0.712313] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.717112] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.721025] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.726827] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.731870] TCP: Hash tables configured (established 65536 bind 65536) [ 0.734700] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.737698] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.740471] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.743666] NET: Registered protocol family 1 [ 0.746694] RPC: Registered named UNIX socket transport module. [ 0.748441] RPC: Registered udp transport module. [ 0.749751] RPC: Registered tcp transport module. [ 0.750831] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.753230] NET: Registered protocol family 44 [ 0.755138] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.757070] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.759067] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.761399] PCI: CLS 0 bytes, default 64 [ 0.763118] Unpacking initramfs... [ 2.157578] debug: unmapping init [mem 0xffff91267cc64000-0xffff91267ffcffff] [ 2.161622] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.164074] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.167089] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.637273] Initialise system trusted keyrings [ 2.638916] Key type blacklist registered [ 2.640575] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.649645] zbud: loaded [ 2.652704] *** VALIDATE nfs *** [ 2.653892] *** VALIDATE nfs4 *** [ 2.655322] pstore: using deflate compression [ 2.658231] Platform Keyring initialized [ 2.739660] NET: Registered protocol family 38 [ 2.741217] Key type asymmetric registered [ 2.742482] Asymmetric key parser 'x509' registered [ 2.743720] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.745983] io scheduler mq-deadline registered [ 2.747317] io scheduler kyber registered [ 2.748862] io scheduler bfq registered [ 2.750306] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.752585] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.754392] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.756276] ACPI: Power Button [PWRF] [ 2.760001] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.764570] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.770611] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.796395] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.824239] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.828944] Non-volatile memory driver v1.3 [ 2.830745] Linux agpgart interface v0.103 [ 2.857241] virtio_blk virtio1: [vda] 146144 512-byte logical blocks (74.8 MB/71.4 MiB) [ 2.859261] vda: detected capacity change from 0 to 74825728 [ 2.870079] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.871995] vdb: detected capacity change from 0 to 1073741824 [ 2.876151] libphy: Fixed MDIO Bus: probed [ 2.887536] usbcore: registered new interface driver usbserial_generic [ 2.889032] usbserial: USB Serial support registered for generic [ 2.890551] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.894404] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.896140] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.898599] mousedev: PS/2 mouse device common for all mice [ 2.901759] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.903295] rtc_cmos 00:05: RTC can wake from S4 [ 2.914603] rtc_cmos 00:05: registered as rtc0 [ 2.916952] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.920518] intel_pstate: CPU model not supported [ 2.920620] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.927634] hid: raw HID events driver (C) Jiri Kosina [ 2.930278] usbcore: registered new interface driver usbhid [ 2.932504] usbhid: USB HID core driver [ 2.933916] drop_monitor: Initializing network drop monitor service [ 2.936739] Initializing XFRM netlink socket [ 2.938138] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.939441] NET: Registered protocol family 10 [ 2.941551] Segment Routing with IPv6 [ 2.946306] NET: Registered protocol family 17 [ 2.947806] mpls_gso: MPLS GSO support [ 2.952124] RAS: Correctable Errors collector initialized. [ 2.953700] AVX version of gcm_enc/dec engaged. [ 2.954688] AES CTR mode by8 optimization enabled [ 3.015480] sched_clock: Marking stable (3015457577, 0)->(3909762371, -894304794) [ 3.020753] registered taskstats version 1 [ 3.023168] Loading compiled-in X.509 certificates [ 3.025264] zswap: loaded using pool lzo/zbud [ 3.049209] Key type big_key registered [ 3.062049] Key type encrypted registered [ 3.063516] ima: No TPM chip found, activating TPM-bypass! [ 3.065408] ima: Allocated hash algorithm: sha1 [ 3.066820] ima: No architecture policies found [ 3.068326] evm: Initialising EVM extended attributes: [ 3.069953] evm: security.selinux [ 3.070914] evm: security.ima [ 3.071834] evm: security.capability [ 3.072880] evm: HMAC attrs: 0x1 [ 3.074946] rtc_cmos 00:05: setting system clock to 2026-08-24 10:50:30 UTC (1787568630) [ 3.080620] debug: unmapping init [mem 0xffffffffb2a03000-0xffffffffb2bfffff] [ 3.083415] debug: unmapping init [mem 0xffffffffb1782000-0xffffffffb1a58fff] [ 3.092120] Write protecting the kernel read-only data: 28672k [ 3.095230] debug: unmapping init [mem 0xffffffffafe03000-0xffffffffafffffff] [ 3.097740] debug: unmapping init [mem 0xffffffffb0714000-0xffffffffb07fffff] [ 3.125873] 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.133604] systemd[1]: Detected virtualization kvm. [ 3.135189] systemd[1]: Detected architecture x86-64. [ 3.136501] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.161045] systemd[1]: No hostname configured. [ 3.162266] systemd[1]: Set hostname to . [ 3.164233] random: systemd: uninitialized urandom read (16 bytes read) [ 3.166807] systemd[1]: Initializing machine ID from random generator. [ 3.305240] random: systemd: uninitialized urandom read (16 bytes read) [ 3.307952] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.312438] random: systemd: uninitialized urandom read (16 bytes read) [ 3.315211] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.319653] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... [ OK ] Reached target Timers. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local Encrypted Volumes. Starting Create Volatile Files and Directories... [ OK ] Reached target Swap. [ OK ] Reached target Slices. Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.852726] device-mapper: uevent: version 1.0.3 [ 3.854770] 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. [ 4.421484] random: fast init done [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.558704] virtio_net virtio0 ens2: renamed from eth0 [ 4.604084] scsi host0: ata_piix [ 4.618334] scsi host1: ata_piix [ 4.620067] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.622476] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.286177] dracut-initqueue[584]: RTNETLINK answers: File exists [ 9.587310] random: crng init done [ 9.588827] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 9.931192] 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. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ 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... [ 11.025871] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.273668] SELinux: Disabled at runtime. [ 11.336891] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.342287] systemd[1]: Detected virtualization kvm. [ 11.344346] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.832101] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.835541] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.840524] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.847299] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.851328] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.859714] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.867129] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [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 Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Control Socket. Mounting POSIX Message Queue File System... [ OK ] Reached target rpc_pipefs.target. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting Kernel Debug File System... [ 11.926646] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Apply Kernel Variables... Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Kernel Socket. Mounting Huge Pages File System... [ OK ] Created slice User and Session Slice. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. Starting udev Coldplug all Devices... [ OK ] Reached target Slices. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Flush Journal to Persistent Storage. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ 12.225227] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Mounted /mnt. [ OK ] Started udev Kernel Device Manager. [ 12.531291] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.545694] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.675294] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.706248] EDAC sbridge: Ver: 1.1.2 [ 13.885974] Key type dns_resolver registered [ 14.174127] NFS: Registering the id_resolver key type [ 14.176106] Key type id_resolver registered [ 14.177610] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ 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. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started irqbalance daemon. [ 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 GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ 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 Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg112-client login: [ 61.965760] libcfs: loading out-of-tree module taints kernel. [ 62.054187] Key type ._llcrypt registered [ 62.056114] Key type .llcrypt registered [ 62.752341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 62.760345] alg: No test for adler32 (adler32-zlib) [ 64.056878] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 64.921913] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 66.632177] Key type lgssc registered [ 68.176742] Lustre: Echo OBD driver; http://www.lustre.org/ [ 194.747412] hrtimer: interrupt took 4491340 ns [ 240.972446] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 245.548317] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 256.012765] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing check_logdir /tmp/testlogs/ [ 261.118857] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing yml_node [ 265.320927] Lustre: DEBUG MARKER: Client: 2.17.57.80 [ 266.721946] Lustre: lustre-OST0000-osc-ffff9126d09c4000: disconnect after 24s idle [ 268.353426] Lustre: DEBUG MARKER: MDS: 2.17.57.80 [ 270.959656] Lustre: DEBUG MARKER: OSS: 2.17.57.80 [ 272.852430] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Aug 24 06:54:58 EDT 2026 [ 290.359553] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 292.102575] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 294.737795] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 305.069861] Lustre: Unmounted lustre-client [ 430.928379] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 437.575725] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 464.042440] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 502.537703] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 06:58:48 (1787569128) [ 535.326740] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 541.236057] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 571.082534] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 571.977877] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 577.304376] Lustre: Unmounted lustre-client [ 607.813616] Key type lgssc unregistered [ 608.176184] LNet: 14682:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 608.185817] LNetError: 14682:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 608.199567] LNet: Removed LNI 192.168.201.12@tcp [ 609.284144] Key type .llcrypt unregistered [ 609.286575] Key type ._llcrypt unregistered [ 627.926477] Key type ._llcrypt registered [ 627.929284] Key type .llcrypt registered [ 629.447862] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 07:00:55 (1787569255) [ 632.414747] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 632.448836] alg: No test for adler32 (adler32-zlib) [ 633.598841] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 633.879455] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 635.672190] Key type lgssc registered [ 636.837756] Lustre: Echo OBD driver; http://www.lustre.org/ [ 696.598135] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 701.928480] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 725.341382] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 737.927124] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 741.510206] Lustre: Unmounted lustre-client [ 769.453882] Key type lgssc unregistered [ 769.682923] LNet: 18371:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 769.697440] LNetError: 18371:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 769.720376] LNet: Removed LNI 192.168.201.12@tcp [ 770.295775] Key type .llcrypt unregistered [ 770.303342] Key type ._llcrypt unregistered [ 785.956791] Key type ._llcrypt registered [ 785.959372] Key type .llcrypt registered [ 787.319539] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 07:03:33 (1787569413) [ 789.411232] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 789.427250] alg: No test for adler32 (adler32-zlib) [ 790.653289] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 790.938889] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 792.584308] Key type lgssc registered [ 793.925496] Lustre: Echo OBD driver; http://www.lustre.org/ [ 847.306285] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 852.731938] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 883.127498] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 890.440904] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 893.432318] Lustre: Unmounted lustre-client [ 924.962302] Key type lgssc unregistered [ 925.439220] LNet: 22035:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 925.465468] LNetError: 22035:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 926.508179] LNet: Removed LNI 192.168.201.12@tcp [ 927.754635] Key type .llcrypt unregistered [ 927.761132] Key type ._llcrypt unregistered [ 946.296936] Key type ._llcrypt registered [ 946.301438] Key type .llcrypt registered [ 947.754659] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 07:06:13 (1787569573) [ 950.385116] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 950.394792] alg: No test for adler32 (adler32-zlib) [ 951.517816] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 951.756391] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 953.451494] Key type lgssc registered [ 954.748717] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1012.894612] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1019.721018] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1045.024209] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1052.236521] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 1057.765346] Lustre: Unmounted lustre-client [ 1086.000203] Key type lgssc unregistered [ 1086.370183] LNet: 25656:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1086.384389] LNetError: 25656:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1086.410508] LNet: Removed LNI 192.168.201.12@tcp [ 1087.765236] Key type .llcrypt unregistered [ 1087.768899] Key type ._llcrypt unregistered [ 1108.619972] Key type ._llcrypt registered [ 1108.622506] Key type .llcrypt registered [ 1111.203849] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 07:08:55 (1787569735) [ 1114.305027] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1114.370283] alg: No test for adler32 (adler32-zlib) [ 1115.443206] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 1115.746728] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 1117.437153] Key type lgssc registered [ 1118.491243] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1173.474470] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1179.998340] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1202.781851] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1205.577325] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 1210.877850] Lustre: lustre-OST0000-osc-ffff9126c5cc5800: Connection to lustre-OST0000 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1219.874467] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1230.195797] Lustre: Unmounted lustre-client [ 1267.799576] Key type lgssc unregistered [ 1268.069970] LNet: 29282:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1268.076449] LNetError: 29282:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1268.112524] LNet: Removed LNI 192.168.201.12@tcp [ 1268.929298] Key type .llcrypt unregistered [ 1268.931950] Key type ._llcrypt unregistered [ 1286.283850] Key type ._llcrypt registered [ 1286.285764] Key type .llcrypt registered [ 1287.375088] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 07:11:53 (1787569913) [ 1289.841506] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1289.878270] alg: No test for adler32 (adler32-zlib) [ 1290.889784] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 1291.118816] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 1292.776241] Key type lgssc registered [ 1293.846689] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1347.421145] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1352.338149] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1375.222577] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1378.153318] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 1383.421542] Lustre: lustre-MDT0000-mdc-ffff9126c6656000: Connection to lustre-MDT0000 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1388.551200] Lustre: lustre-MDT0001-mdc-ffff9126c6656000: Connection to lustre-MDT0001 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1395.492722] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1398.755772] Lustre: 30240:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787570010/real 1787570010] req@000000000d96fbc2 x1874402914342016/t0(0) o400->MGC192.168.201.112@tcp@192.168.201.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1787570026 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1398.788192] LustreError: MGC192.168.201.112@tcp: Connection to MGS (at 192.168.201.112@tcp) was lost; in progress operations using this service will fail [ 1405.877779] Lustre: Unmounted lustre-client [ 1424.499431] Key type lgssc unregistered [ 1424.705656] LNet: 32940:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1424.710711] LNetError: 32940:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1424.723319] LNet: Removed LNI 192.168.201.12@tcp [ 1425.254173] Key type .llcrypt unregistered [ 1425.257043] Key type ._llcrypt unregistered [ 1441.562354] Key type ._llcrypt registered [ 1441.566262] Key type .llcrypt registered [ 1442.721342] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 07:14:28 (1787570068) [ 1444.937559] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1445.053640] alg: No test for adler32 (adler32-zlib) [ 1446.157348] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 1446.353691] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 1448.032195] Key type lgssc registered [ 1449.150995] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1529.067162] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1534.752712] LustreError: 35139:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.112@tcp: failed processing log, type 1: rc = -110 [ 1543.136657] LustreError: 35148:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1691.618737] LustreError: MGC192.168.201.112@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 [ 1691.636181] Lustre: Unmounted lustre-client [ 1691.647293] LustreError: 35139:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1702.579238] Key type lgssc unregistered [ 1702.796637] LNet: 35568:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1702.805278] LNetError: 35568:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1702.846930] LNet: Removed LNI 192.168.201.12@tcp [ 1703.323737] Key type .llcrypt unregistered [ 1703.326415] Key type ._llcrypt unregistered [ 1719.005942] Key type ._llcrypt registered [ 1719.008112] Key type .llcrypt registered [ 1720.295776] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 07:19:06 (1787570346) [ 1723.066595] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1723.078368] alg: No test for adler32 (adler32-zlib) [ 1724.150759] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 1724.473285] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 1726.136399] Key type lgssc registered [ 1727.403620] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1779.057428] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1785.483953] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1810.689515] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1811.511762] LustreError: 38660:0:(llite_lib.c:1555:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1811.518831] Lustre: Unmounted wrong.lustre-client [ 1811.554139] LustreError: 38660:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1847.141287] Key type lgssc unregistered [ 1847.489822] LNet: 39081:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1847.497497] LNetError: 39081:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1847.530040] LNet: Removed LNI 192.168.201.12@tcp [ 1848.450166] Key type .llcrypt unregistered [ 1848.452943] Key type ._llcrypt unregistered [ 1865.201414] Key type ._llcrypt registered [ 1865.206399] Key type .llcrypt registered [ 1866.625227] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 07:21:32 (1787570492) [ 1868.796659] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1868.822300] alg: No test for adler32 (adler32-zlib) [ 1870.004853] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 1870.271094] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 1871.947271] Key type lgssc registered [ 1873.156784] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1957.214703] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1995.018803] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2001.240227] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2013.896669] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 2016.858490] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2027.205870] Lustre: Unmounted lustre-client [ 2055.489370] Key type lgssc unregistered [ 2055.722787] LNet: 42656:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2055.730980] LNetError: 42656:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2055.748188] LNet: Removed LNI 192.168.201.12@tcp [ 2056.318651] Key type .llcrypt unregistered [ 2056.321449] Key type ._llcrypt unregistered [ 2074.064631] Key type ._llcrypt registered [ 2074.069530] Key type .llcrypt registered [ 2075.540221] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 07:25:01 (1787570701) [ 2077.477048] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2077.491664] alg: No test for adler32 (adler32-zlib) [ 2078.557124] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 2078.804819] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 2080.536513] Key type lgssc registered [ 2081.713570] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2137.518065] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2143.860651] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2168.265371] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2170.082931] LustreError: 43618:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2174.176609] LustreError: 45740:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.112@tcp: failed processing log, type 1: rc = -110 [ 2180.186535] LustreError: 43618:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2181.548836] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 2184.974764] Lustre: Unmounted lustre-client [ 2225.608445] Key type lgssc unregistered [ 2225.889246] LNet: 46202:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2225.905134] LNetError: 46202:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2225.941793] LNet: Removed LNI 192.168.201.12@tcp [ 2226.759897] Key type .llcrypt unregistered [ 2226.762717] Key type ._llcrypt unregistered [ 2244.915763] Key type ._llcrypt registered [ 2244.919258] Key type .llcrypt registered [ 2246.481743] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 07:27:52 (1787570872) [ 2248.573063] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2250.878409] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 07:27:56 (1787570876) [ 2252.332034] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2252.532987] alg: No test for adler32 (adler32-zlib) [ 2253.639992] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 2253.886442] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 2255.568151] Key type lgssc registered [ 2256.801541] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2282.375623] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 07:28:28 (1787570908) [ 2319.454905] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2326.434406] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2355.603499] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2357.927993] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 2363.388394] Lustre: lustre-MDT0000-mdc-ffff9126c4ac1800: Connection to lustre-MDT0000 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2383.841373] Lustre: 47423:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787570995/real 1787570995] req@00000000e5e89b96 x1874403924119680/t0(0) o400->MGC192.168.201.112@tcp@192.168.201.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1787571011 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2383.880896] LustreError: MGC192.168.201.112@tcp: Connection to MGS (at 192.168.201.112@tcp) was lost; in progress operations using this service will fail [ 2390.807382] Lustre: lustre-MDT0000-mdc-ffff9126c4ac1800: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 2394.404751] Lustre: Evicted from MGS (at 192.168.201.112@tcp) after server handle changed from 0xebb0cbaa955296cd to 0xebb0cbaa95529a54 [ 2394.415230] Lustre: MGC192.168.201.112@tcp: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 2395.981703] Lustre: Unmounted lustre-client [ 2436.621581] Key type lgssc unregistered [ 2436.944493] LNet: 51069:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2436.949371] LNetError: 51069:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2436.987795] LNet: Removed LNI 192.168.201.12@tcp [ 2438.155201] Key type .llcrypt unregistered [ 2438.167300] Key type ._llcrypt unregistered [ 2458.923706] Key type ._llcrypt registered [ 2458.925854] Key type .llcrypt registered [ 2460.630577] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 07:31:26 (1787571086) [ 2463.426376] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2463.493607] alg: No test for adler32 (adler32-zlib) [ 2464.536499] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 2464.774478] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 2466.448498] Key type lgssc registered [ 2467.709694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2529.304799] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2534.862585] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2556.827669] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2559.552813] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 2564.602948] Lustre: lustre-MDT0000-mdc-ffff9126c3d74800: Connection to lustre-MDT0000 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2580.960210] Lustre: 52030:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787571192/real 1787571192] req@00000000911bc354 x1874404145368704/t0(0) o400->MGC192.168.201.112@tcp@192.168.201.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1787571208 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2580.990630] LustreError: MGC192.168.201.112@tcp: Connection to MGS (at 192.168.201.112@tcp) was lost; in progress operations using this service will fail [ 2587.436178] Lustre: lustre-MDT0000-mdc-ffff9126c3d74800: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 2592.437556] Lustre: Evicted from MGS (at 192.168.201.112@tcp) after server handle changed from 0xbc2a4d3ffb7d798 to 0xbc2a4d3ffb7db49 [ 2592.446590] Lustre: MGC192.168.201.112@tcp: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 2593.326360] Lustre: Unmounted lustre-client [ 2629.868297] Key type lgssc unregistered [ 2630.078441] LNet: 55036:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2630.087616] LNetError: 55036:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2630.107520] LNet: Removed LNI 192.168.201.12@tcp [ 2630.859479] Key type .llcrypt unregistered [ 2630.861570] Key type ._llcrypt unregistered [ 2646.695656] Key type ._llcrypt registered [ 2646.699532] Key type .llcrypt registered [ 2647.973224] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 07:34:33 (1787571273) [ 2648.668315] Key type .llcrypt unregistered [ 2648.669968] Key type ._llcrypt unregistered [ 2653.809598] Key type ._llcrypt registered [ 2653.813721] Key type .llcrypt registered [ 2653.888487] Key type .llcrypt unregistered [ 2653.890080] Key type ._llcrypt unregistered [ 2659.203673] Key type ._llcrypt registered [ 2659.206215] Key type .llcrypt registered [ 2660.770831] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 07:34:46 (1787571286) [ 2663.261567] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2663.275654] alg: No test for adler32 (adler32-zlib) [ 2664.316039] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 2664.535429] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 2666.192543] Key type lgssc registered [ 2667.970758] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2723.181656] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2728.666973] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2753.508567] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2755.395338] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 2761.102083] Lustre: Unmounted lustre-client [ 2761.670930] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 2764.732261] Lustre: Unmounted lustre-client [ 2795.792989] Key type lgssc unregistered [ 2796.023963] LNet: 59457:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2796.030910] LNetError: 59457:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2796.062112] LNet: Removed LNI 192.168.201.12@tcp [ 2796.824216] Key type .llcrypt unregistered [ 2796.826939] Key type ._llcrypt unregistered [ 2814.896505] Key type ._llcrypt registered [ 2814.899725] Key type .llcrypt registered [ 2816.515994] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 07:37:22 (1787571442) [ 2819.866924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2819.882462] alg: No test for adler32 (adler32-zlib) [ 2821.102658] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 2821.388112] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 2823.072336] Key type lgssc registered [ 2824.442455] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2884.089933] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2891.356544] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2918.065425] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2924.165859] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 2927.030147] Lustre: Unmounted lustre-client [ 2957.091855] Key type lgssc unregistered [ 2957.473133] LNet: 63032:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2957.484386] LNetError: 63032:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2957.514961] LNet: Removed LNI 192.168.201.12@tcp [ 2958.814791] Key type .llcrypt unregistered [ 2958.817221] Key type ._llcrypt unregistered [ 2978.976363] Key type ._llcrypt registered [ 2978.978506] Key type .llcrypt registered [ 2980.646421] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 07:40:06 (1787571606) [ 2983.846150] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2983.941173] alg: No test for adler32 (adler32-zlib) [ 2985.097628] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 2985.350971] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 2987.080219] Key type lgssc registered [ 2988.615356] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3048.422496] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3054.231755] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3076.038339] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3078.959577] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 3084.864180] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 3088.555989] Lustre: Unmounted lustre-client [ 3090.217317] Lustre: Unmounted lustre-client [ 3126.017307] Key type lgssc unregistered [ 3126.271943] LNet: 66693:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3126.283667] LNetError: 66693:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3126.313403] LNet: Removed LNI 192.168.201.12@tcp [ 3127.101516] Key type .llcrypt unregistered [ 3127.104124] Key type ._llcrypt unregistered [ 3141.841903] Key type ._llcrypt registered [ 3141.845983] Key type .llcrypt registered [ 3143.040975] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 07:42:49 (1787571769) [ 3146.204381] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3146.234902] alg: No test for adler32 (adler32-zlib) [ 3147.533482] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 3147.816182] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 3149.530067] Key type lgssc registered [ 3150.556260] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3240.293624] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3257.607579] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 07:44:43 (1787571883) [ 3298.996451] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3306.752186] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3336.569365] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3339.468659] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 3350.781666] Lustre: Unmounted lustre-client [ 3381.587260] Key type lgssc unregistered [ 3382.013277] LNet: 71465:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3382.023230] LNetError: 71465:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3382.057192] LNet: Removed LNI 192.168.201.12@tcp [ 3382.901798] Key type .llcrypt unregistered [ 3382.904771] Key type ._llcrypt unregistered [ 3402.067267] Key type ._llcrypt registered [ 3402.069534] Key type .llcrypt registered [ 3403.416685] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 07:47:09 (1787572029) [ 3425.526896] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 07:47:31 (1787572051) [ 3429.419995] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3429.436345] alg: No test for adler32 (adler32-zlib) [ 3430.857415] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 3431.341814] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 3433.192242] Key type lgssc registered [ 3435.152943] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3503.167661] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3511.185430] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3540.813149] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3543.606287] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 3549.047702] Lustre: Unmounted lustre-client [ 3579.808883] Key type lgssc unregistered [ 3580.036774] LNet: 75763:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3580.049318] LNetError: 75763:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3580.065071] LNet: Removed LNI 192.168.201.12@tcp [ 3580.797304] Key type .llcrypt unregistered [ 3580.800409] Key type ._llcrypt unregistered [ 3595.273749] Key type ._llcrypt registered [ 3595.279490] Key type .llcrypt registered [ 3595.627406] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3595.640200] alg: No test for adler32 (adler32-zlib) [ 3596.674200] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 3596.952639] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 3598.672232] Key type lgssc registered [ 3599.959980] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3700.392702] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3841.172664] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3848.075693] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3874.546469] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3923.875989] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 07:55:49 (1787572549) [ 3927.714359] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 4045.394587] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4052.242256] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4078.500027] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4153.441836] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4159.825264] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4186.854986] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4188.735949] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 4194.343839] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 4196.119679] Lustre: Unmounted lustre-client [ 4219.886325] Key type lgssc unregistered [ 4220.236801] LNet: 87469:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4220.240874] LNetError: 87469:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4221.289684] LNet: Removed LNI 192.168.201.12@tcp [ 4222.232129] Key type .llcrypt unregistered [ 4222.235704] Key type ._llcrypt unregistered [ 4257.892971] Key type ._llcrypt registered [ 4257.904325] Key type .llcrypt registered [ 4258.783280] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4258.825425] alg: No test for adler32 (adler32-zlib) [ 4259.917169] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 4260.264091] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 4261.929310] Key type lgssc registered [ 4263.664725] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4361.203927] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4368.082077] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4395.173589] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4447.024986] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 08:04:33 (1787573073) [ 4487.159845] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4493.332529] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4517.474860] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 08:05:43 (1787573143) [ 4588.520692] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4600.489351] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 08:07:06 (1787573226) [ 4636.724938] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4644.401596] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4669.768719] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4671.702287] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 4672.500285] Lustre: Remounted lustre-client read-only [ 4673.080921] Lustre: Remounted lustre-client read-write [ 4675.551775] Lustre: Unmounted lustre-client [ 4714.152952] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 08:08:59 (1787573339) [ 4749.806640] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4759.067990] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4793.596132] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4842.532897] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 08:11:07 (1787573467) [ 4916.751352] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4953.450684] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4960.109367] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5010.579786] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 08:13:56 (1787573636) [ 5080.030399] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5119.435538] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5123.815443] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5147.967802] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5225.372408] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 08:17:31 (1787573851) [ 5227.172448] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 5229.809989] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 5232.180362] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 08:17:37 (1787573857) [ 5272.550661] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5277.821919] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5303.479734] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5325.249617] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 5328.142320] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5338.493295] Lustre: Unmounted lustre-client [ 5361.800110] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5362.469421] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 5380.768615] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9126e0441800.ost_server_uuid 50 [ 5382.539427] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9126e0441800.ost_server_uuid in FULL state after 0 sec [ 5385.612573] Lustre: Unmounted lustre-client [ 5427.925206] Key type lgssc unregistered [ 5428.312302] LNet: 107858:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5428.320700] LNetError: 107858:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5428.333593] LNet: Removed LNI 192.168.201.12@tcp [ 5428.941831] Key type .llcrypt unregistered [ 5428.943361] Key type ._llcrypt unregistered [ 5446.601841] Key type ._llcrypt registered [ 5446.605291] Key type .llcrypt registered [ 5448.118982] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 08:21:13 (1787574073) [ 5451.565728] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5451.617331] alg: No test for adler32 (adler32-zlib) [ 5452.839412] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 5453.124548] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 5454.848299] Key type lgssc registered [ 5456.273196] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5509.245668] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5515.637320] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5542.766332] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5546.080663] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 5554.327929] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5564.638518] LustreError: MGC192.168.201.112@tcp: Connection to MGS (at 192.168.201.112@tcp) was lost; in progress operations using this service will fail [ 5564.698394] Lustre: Unmounted lustre-client [ 5589.884990] LustreError: lustre-MDT0000-mdc-ffff9126d0676000: operation mds_connect to node 192.168.201.112@tcp failed: rc = -16 [ 5594.738311] LustreError: 111246:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9126d0676000: can't stat MDS #0: rc = -16 [ 5594.836393] Lustre: Unmounted lustre-client [ 5594.851802] LustreError: 111246:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 5630.019605] Key type lgssc unregistered [ 5630.421226] LNet: 111704:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5630.426736] LNetError: 111704:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5630.453186] LNet: Removed LNI 192.168.201.12@tcp [ 5631.550253] Key type .llcrypt unregistered [ 5631.555083] Key type ._llcrypt unregistered [ 5651.502261] Key type ._llcrypt registered [ 5651.504560] Key type .llcrypt registered [ 5652.804353] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 08:24:38 (1787574278) [ 5655.527508] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5655.543604] alg: No test for adler32 (adler32-zlib) [ 5656.658712] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 5656.937979] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 5658.680245] Key type lgssc registered [ 5660.307434] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5716.930839] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5725.531989] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5753.508995] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5759.532189] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 5762.728832] Lustre: Unmounted lustre-client [ 5793.158242] Key type lgssc unregistered [ 5793.530406] LNet: 115242:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5793.535485] LNetError: 115242:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5793.555727] LNet: Removed LNI 192.168.201.12@tcp [ 5794.209778] Key type .llcrypt unregistered [ 5794.212275] Key type ._llcrypt unregistered [ 5809.447676] Key type ._llcrypt registered [ 5809.449415] Key type .llcrypt registered [ 5810.852677] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 08:27:16 (1787574436) [ 5813.828201] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 5816.045050] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 08:27:21 (1787574441) [ 5819.216029] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 5820.982705] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 08:27:26 (1787574446) [ 5825.066117] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5825.107035] alg: No test for adler32 (adler32-zlib) [ 5826.367759] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 5826.853627] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 5828.688738] Key type lgssc registered [ 5830.067194] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5884.995843] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5890.574159] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5913.574498] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5916.289893] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 5918.715927] Lustre: Unmounted lustre-client [ 5948.957440] Key type lgssc unregistered [ 5949.258910] LNet: 119435:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5949.265727] LNetError: 119435:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5950.324176] LNet: Removed LNI 192.168.201.12@tcp [ 5951.035709] Key type .llcrypt unregistered [ 5951.038639] Key type ._llcrypt unregistered [ 5968.252876] Key type ._llcrypt registered [ 5968.255508] Key type .llcrypt registered [ 5969.847655] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 08:29:55 (1787574595) [ 5971.342684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5971.358031] alg: No test for adler32 (adler32-zlib) [ 5972.446837] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 5972.645753] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 5974.322172] Key type lgssc registered [ 5975.400488] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6048.183828] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6053.819193] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6073.711694] Key type lgssc unregistered [ 6073.963907] LNet: 122538:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6073.970266] LNetError: 122538:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6073.983297] LNet: Removed LNI 192.168.201.12@tcp [ 6074.666413] Key type .llcrypt unregistered [ 6074.670221] Key type ._llcrypt unregistered [ 6089.788311] Key type ._llcrypt registered [ 6089.790699] Key type .llcrypt registered [ 6091.023288] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 08:31:57 (1787574717) [ 6099.965410] Key type .llcrypt unregistered [ 6099.971267] Key type ._llcrypt unregistered [ 6108.233593] Key type ._llcrypt registered [ 6108.237566] Key type .llcrypt registered [ 6108.647802] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6108.693253] alg: No test for adler32 (adler32-zlib) [ 6109.809654] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 6110.031549] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 6111.672246] Key type lgssc registered [ 6112.781507] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6197.938851] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6231.551314] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6239.281400] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6295.593850] Key type lgssc unregistered [ 6295.907416] LNet: 126402:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6295.913760] LNetError: 126402:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6295.934989] LNet: Removed LNI 192.168.201.12@tcp [ 6297.044735] Key type .llcrypt unregistered [ 6297.051971] Key type ._llcrypt unregistered [ 6313.247880] Key type ._llcrypt registered [ 6313.255844] Key type .llcrypt registered [ 6314.922932] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 08:35:40 (1787574940) [ 6317.199483] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6317.255526] alg: No test for adler32 (adler32-zlib) [ 6318.440140] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 6318.810242] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 6320.632300] Key type lgssc registered [ 6322.137202] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6378.307444] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6384.071614] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6403.524346] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6406.207480] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 6411.240120] Lustre: lustre-MDT0000-mdc-ffff9126d1dca800: Connection to lustre-MDT0000 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6427.491110] Lustre: 127375:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787575038/real 1787575038] req@00000000dd23da27 x1874408186580096/t0(0) o400->MGC192.168.201.112@tcp@192.168.201.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1787575054 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6427.524188] LustreError: MGC192.168.201.112@tcp: Connection to MGS (at 192.168.201.112@tcp) was lost; in progress operations using this service will fail [ 6427.554752] Lustre: Evicted from MGS (at 192.168.201.112@tcp) after server handle changed from 0xee8851f782498fb1 to 0xee8851f782499101 [ 6427.569486] Lustre: MGC192.168.201.112@tcp: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 6432.529893] Lustre: lustre-MDT0000-mdc-ffff9126d1dca800: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 6446.115263] Lustre: Unmounted lustre-client [ 6468.930757] Key type lgssc unregistered [ 6469.502201] LNet: 130376:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6469.514719] LNetError: 130376:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6469.532211] LNet: Removed LNI 192.168.201.12@tcp [ 6470.782976] Key type .llcrypt unregistered [ 6470.787810] Key type ._llcrypt unregistered [ 6488.477995] Key type ._llcrypt registered [ 6488.483252] Key type .llcrypt registered [ 6489.508664] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 08:38:35 (1787575115) [ 6492.549652] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6492.609731] alg: No test for adler32 (adler32-zlib) [ 6493.625511] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 6493.798458] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 6495.424220] Key type lgssc registered [ 6496.739779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6550.676220] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6557.361549] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6582.167504] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6593.588407] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 6611.297054] Lustre: Unmounted lustre-client [ 6611.735720] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 6624.348395] Lustre: Unmounted lustre-client [ 6663.648291] Key type lgssc unregistered [ 6664.346140] LNet: 134609:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6664.353730] LNetError: 134609:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6664.393356] LNet: Removed LNI 192.168.201.12@tcp [ 6666.179095] Key type .llcrypt unregistered [ 6666.180707] Key type ._llcrypt unregistered [ 6677.758048] Key type ._llcrypt registered [ 6677.759711] Key type .llcrypt registered [ 6679.183603] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 08:41:45 (1787575305) [ 6681.534080] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6681.555742] alg: No test for adler32 (adler32-zlib) [ 6682.669717] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 6682.982264] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 6684.752303] Key type lgssc registered [ 6685.902910] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6734.222792] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6739.421905] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6765.237595] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6767.183861] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 6810.564097] Lustre: Unmounted lustre-client [ 6839.351264] Key type lgssc unregistered [ 6839.612087] LNet: 138016:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6839.618673] LNetError: 138016:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6839.641436] LNet: Removed LNI 192.168.201.12@tcp [ 6840.335375] Key type .llcrypt unregistered [ 6840.341810] Key type ._llcrypt unregistered [ 6857.939945] Key type ._llcrypt registered [ 6857.943707] Key type .llcrypt registered [ 6859.651923] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 08:44:45 (1787575485) [ 6862.378641] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6862.400055] alg: No test for adler32 (adler32-zlib) [ 6863.464182] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 6863.687595] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 6865.376794] Key type lgssc registered [ 6866.805157] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6920.466433] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6927.995431] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6960.309174] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6967.932741] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 6972.036031] Lustre: Unmounted lustre-client [ 6972.576210] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 6982.506572] Lustre: Unmounted lustre-client [ 7018.609931] Key type lgssc unregistered [ 7018.846088] LNet: 142069:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7018.852285] LNetError: 142069:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7018.869455] LNet: Removed LNI 192.168.201.12@tcp [ 7019.487263] Key type .llcrypt unregistered [ 7019.490675] Key type ._llcrypt unregistered [ 7030.005030] Key type ._llcrypt registered [ 7030.020861] Key type .llcrypt registered [ 7031.997320] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 08:47:37 (1787575657) [ 7035.473749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7035.530833] alg: No test for adler32 (adler32-zlib) [ 7036.694914] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 7037.046588] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 7038.680207] Key type lgssc registered [ 7039.767553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7104.587725] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7111.537044] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7136.465591] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7143.533727] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 7146.144804] Lustre: Unmounted lustre-client [ 7146.783519] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 7155.130218] Lustre: Unmounted lustre-client [ 7183.459192] Key type lgssc unregistered [ 7183.884193] LNet: 145693:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7183.907468] LNetError: 145693:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7183.956484] LNet: Removed LNI 192.168.201.12@tcp [ 7185.190925] Key type .llcrypt unregistered [ 7185.203388] Key type ._llcrypt unregistered [ 7200.134582] Key type ._llcrypt registered [ 7200.138188] Key type .llcrypt registered [ 7202.579598] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 08:50:27 (1787575827) [ 7206.010707] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7206.103325] alg: No test for adler32 (adler32-zlib) [ 7207.367446] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 7207.646952] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 7209.392333] Key type lgssc registered [ 7211.269891] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7273.217366] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7280.297476] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7305.249034] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7312.435888] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 7337.551464] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7339.284477] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7358.489680] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7365.139343] Lustre: Unmounted lustre-client [ 7365.675776] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7365.826544] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 7374.334645] Lustre: Unmounted lustre-client [ 7419.304366] Key type lgssc unregistered [ 7419.599778] LNet: 149731:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7419.617669] LNetError: 149731:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7419.647259] LNet: Removed LNI 192.168.201.12@tcp [ 7420.660414] Key type .llcrypt unregistered [ 7420.664582] Key type ._llcrypt unregistered [ 7460.720200] Key type ._llcrypt registered [ 7460.723181] Key type .llcrypt registered [ 7462.109496] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 08:54:47 (1787576087) [ 7464.780916] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7464.822443] alg: No test for adler32 (adler32-zlib) [ 7465.968214] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 7466.223518] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 7467.888254] Key type lgssc registered [ 7469.572759] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7526.969993] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7534.160909] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7558.020942] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7561.084477] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 7731.039037] Lustre: Unmounted lustre-client [ 7731.909137] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 7736.218383] Lustre: Unmounted lustre-client [ 7736.962338] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 7741.169959] Lustre: Unmounted lustre-client [ 7771.626429] Key type lgssc unregistered [ 7771.896620] LNet: 154983:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7771.901019] LNetError: 154983:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7772.972709] LNet: Removed LNI 192.168.201.12@tcp [ 7773.745115] Key type .llcrypt unregistered [ 7773.746990] Key type ._llcrypt unregistered [ 7792.419723] Key type ._llcrypt registered [ 7792.421587] Key type .llcrypt registered [ 7794.086361] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 09:00:19 (1787576419) [ 7797.366307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7797.431059] alg: No test for adler32 (adler32-zlib) [ 7798.510935] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 7798.875563] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 7800.691687] Key type lgssc registered [ 7802.063991] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7864.427568] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7872.290335] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7899.180433] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7901.474748] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 7909.785632] Lustre: Unmounted lustre-client [ 7910.293773] Lustre: Mounted lustre-client - version 2.17.57_80_ga77f2be [ 7912.840625] Lustre: Unmounted lustre-client [ 7941.989549] Key type lgssc unregistered [ 7942.341415] LNet: 158703:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7942.349984] LNetError: 158703:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7942.365573] LNet: Removed LNI 192.168.201.12@tcp [ 7943.358430] Key type .llcrypt unregistered [ 7943.360711] Key type ._llcrypt unregistered [ 7959.969267] Key type ._llcrypt registered [ 7959.971277] Key type .llcrypt registered [ 7961.011206] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 09:03:07 (1787576587) [ 7962.849404] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7962.878544] alg: No test for adler32 (adler32-zlib) [ 7964.078409] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 7964.344725] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 7966.072259] Key type lgssc registered [ 7967.348432] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7990.758451] LustreError: 160399:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 7997.920676] LustreError: 160409:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 8042.208663] LNet: 1 local NIs in recovery (showing 1): 192.168.201.12@tcp [ 8115.939471] LNet: 1 local NIs in recovery (showing 1): 192.168.201.12@tcp [ 8146.912752] 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 [ 8146.929782] Lustre: Unmounted lustre-client [ 8146.936703] LustreError: 160399:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 8161.613657] Key type lgssc unregistered [ 8170.213678] LNet: 160820:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8170.231384] LNetError: 160820:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8247.080381] LNet: Removed LNI 192.168.201.12@tcp [ 8247.888626] Key type .llcrypt unregistered [ 8247.893585] Key type ._llcrypt unregistered [ 8305.084413] Key type ._llcrypt registered [ 8305.089183] Key type .llcrypt registered [ 8305.517815] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8305.575524] alg: No test for adler32 (adler32-zlib) [ 8306.931993] Lustre: Lustre: Build Version: 2.17.57_80_ga77f2be [ 8307.458309] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 8309.243631] Key type lgssc registered [ 8310.917311] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8368.235606] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 8094 sec ========== 09:09:54 (1787576994) [ 8370.498700] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 09:09:56 (1787576996) === [ 8374.607494] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 09:10:00 (1787577000) === [ 8418.580099] Key type lgssc unregistered [ 8419.030302] LNet: 165072:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8419.044846] LNetError: 165072:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8419.123420] LNet: Removed LNI 192.168.201.12@tcp [ 8420.033742] Key type .llcrypt unregistered [ 8420.035578] Key type ._llcrypt unregistered