[ 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 517143580 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 2496MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.003339] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005019] kvm-guest: setup PV IPIs [ 0.007881] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009008] pid_max: default: 32768 minimum: 301 [ 0.010130] LSM: Security Framework initializing [ 0.011040] Yama: becoming mindful. [ 0.012029] SELinux: Initializing. [ 0.013061] *** VALIDATE selinux *** [ 0.021494] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026176] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027145] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028091] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029101] *** VALIDATE tmpfs *** [ 0.030410] *** VALIDATE proc *** [ 0.031200] *** VALIDATE cgroup *** [ 0.032006] *** VALIDATE cgroup2 *** [ 0.033227] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034130] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035007] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036022] Spectre V2 : User space: Vulnerable [ 0.037005] Speculative Store Bypass: Vulnerable [ 0.040472] debug: unmapping init [mem 0xffffffffab059000-0xffffffffab060fff] [ 0.042235] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043630] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044017] ... version: 2 [ 0.045008] ... bit width: 48 [ 0.046008] ... generic registers: 4 [ 0.047007] ... value mask: 0000ffffffffffff [ 0.048010] ... max period: 00007fffffffffff [ 0.049012] ... fixed-purpose events: 3 [ 0.050008] ... event mask: 000000070000000f [ 0.051252] rcu: Hierarchical SRCU implementation. [ 0.053471] smp: Bringing up secondary CPUs ... [ 0.054583] x86: Booting SMP configuration: [ 0.055015] .... node #0, CPUs: #1 #2 #3 [ 0.064046] smp: Brought up 1 node, 4 CPUs [ 0.066010] smpboot: Max logical packages: 1 [ 0.067011] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.103151] node 0 deferred pages initialised in 34ms [ 0.107498] devtmpfs: initialized [ 0.108348] x86/mm: Memory block size: 128MB [ 0.111407] gcov: version magic: 0x41383552 [ 0.113328] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.117159] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.119398] pinctrl core: initialized pinctrl subsystem [ 0.122177] [ 0.122760] ************************************************************* [ 0.126008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.128011] ** ** [ 0.131009] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.134008] ** ** [ 0.137009] ** This means that this kernel is built to expose internal ** [ 0.141010] ** IOMMU data structures, which may compromise security on ** [ 0.146016] ** your system. ** [ 0.150015] ** ** [ 0.155010] ** If you see this message and you are not debugging the ** [ 0.161034] ** kernel, report this immediately to your vendor! ** [ 0.165020] ** ** [ 0.170011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.176019] ************************************************************* [ 0.182556] NET: Registered protocol family 16 [ 0.188315] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.194199] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.201117] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.207006] cpuidle: using governor menu [ 0.211513] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.217837] PCI: Using configuration type 1 for base access [ 0.221208] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.239084] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.243021] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.248350] cryptd: max_cpu_qlen set to 1000 [ 0.250515] ACPI: Added _OSI(Module Device) [ 0.253024] ACPI: Added _OSI(Processor Device) [ 0.256019] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.259008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.269303] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.280451] ACPI: Interpreter enabled [ 0.284065] ACPI: PM: (supports S0 S3 S4 S5) [ 0.286070] ACPI: Using IOAPIC for interrupt routing [ 0.290193] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.296842] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.321626] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.326055] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.332085] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.340148] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.350107] acpiphp: Slot [2] registered [ 0.353111] acpiphp: Slot [5] registered [ 0.355086] acpiphp: Slot [6] registered [ 0.358119] acpiphp: Slot [3] registered [ 0.360070] acpiphp: Slot [4] registered [ 0.362101] acpiphp: Slot [7] registered [ 0.364122] acpiphp: Slot [8] registered [ 0.367095] acpiphp: Slot [9] registered [ 0.369127] acpiphp: Slot [10] registered [ 0.372219] acpiphp: Slot [11] registered [ 0.374124] acpiphp: Slot [12] registered [ 0.377090] acpiphp: Slot [13] registered [ 0.379064] acpiphp: Slot [14] registered [ 0.380062] acpiphp: Slot [15] registered [ 0.382096] acpiphp: Slot [16] registered [ 0.384078] acpiphp: Slot [17] registered [ 0.387131] acpiphp: Slot [18] registered [ 0.389071] acpiphp: Slot [19] registered [ 0.391078] acpiphp: Slot [20] registered [ 0.392079] acpiphp: Slot [21] registered [ 0.394099] acpiphp: Slot [22] registered [ 0.395000] acpiphp: Slot [23] registered [ 0.395000] acpiphp: Slot [24] registered [ 0.395089] acpiphp: Slot [25] registered [ 0.396000] acpiphp: Slot [26] registered [ 0.399070] acpiphp: Slot [27] registered [ 0.401175] acpiphp: Slot [28] registered [ 0.402068] acpiphp: Slot [29] registered [ 0.405115] acpiphp: Slot [30] registered [ 0.407139] acpiphp: Slot [31] registered [ 0.408057] PCI host bridge to bus 0000:00 [ 0.412028] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.417022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.446034] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.449025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.453049] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.456017] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.458157] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.462338] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.465448] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.474018] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.479418] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.483021] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.485015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.488017] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.490717] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.493777] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.494041] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.495000] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.495000] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.503000] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.506012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.511897] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.517000] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.519011] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.527000] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.529000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.537937] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.543033] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.555011] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.573000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.573376] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.575333] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.576357] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.578174] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.581153] iommu: Default domain type: Passthrough [ 0.582571] SCSI subsystem initialized [ 0.583114] ACPI: bus type USB registered [ 0.585130] usbcore: registered new interface driver usbfs [ 0.587071] usbcore: registered new interface driver hub [ 0.589071] usbcore: registered new device driver usb [ 0.591174] pps_core: LinuxPPS API ver. 1 registered [ 0.592007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.595081] PTP clock support registered [ 0.597303] EDAC MC: Ver: 3.0.0 [ 0.599318] PCI: Using ACPI for IRQ routing [ 0.601065] NetLabel: Initializing [ 0.603009] NetLabel: domain hash size = 128 [ 0.604007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.606167] NetLabel: unlabeled traffic allowed by default [ 0.609345] vgaarb: loaded [ 0.611740] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.613015] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.622389] clocksource: Switched to clocksource kvm-clock [ 0.749112] VFS: Disk quotas dquot_6.6.0 [ 0.750842] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.753744] *** VALIDATE ramfs *** [ 0.755082] *** VALIDATE hugetlbfs *** [ 0.756860] pnp: PnP ACPI init [ 0.760112] pnp: PnP ACPI: found 6 devices [ 0.776514] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.780574] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.786954] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.789890] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.792895] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.796096] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.799679] NET: Registered protocol family 2 [ 0.802510] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.810640] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.814545] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.821699] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.825622] TCP: Hash tables configured (established 65536 bind 65536) [ 0.829623] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.834559] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.837466] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.840918] NET: Registered protocol family 1 [ 0.844108] RPC: Registered named UNIX socket transport module. [ 0.849096] RPC: Registered udp transport module. [ 0.850745] RPC: Registered tcp transport module. [ 0.852342] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.854743] NET: Registered protocol family 44 [ 0.857474] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.860457] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.863457] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.866366] PCI: CLS 0 bytes, default 64 [ 0.867981] Unpacking initramfs... [ 3.121793] debug: unmapping init [mem 0xffffa0873cc64000-0xffffa0873ffcffff] [ 3.130849] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.133815] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 3.142374] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.958125] Initialise system trusted keyrings [ 3.960017] Key type blacklist registered [ 3.962753] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.977146] zbud: loaded [ 3.981564] *** VALIDATE nfs *** [ 3.983948] *** VALIDATE nfs4 *** [ 3.986828] pstore: using deflate compression [ 3.991379] Platform Keyring initialized [ 4.158374] NET: Registered protocol family 38 [ 4.161583] Key type asymmetric registered [ 4.165384] Asymmetric key parser 'x509' registered [ 4.167984] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.173280] io scheduler mq-deadline registered [ 4.177478] io scheduler kyber registered [ 4.183625] io scheduler bfq registered [ 4.188134] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.191342] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.199234] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.202538] ACPI: Power Button [PWRF] [ 4.214649] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.225728] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.240466] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.272456] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.313364] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.319414] Non-volatile memory driver v1.3 [ 4.322212] Linux agpgart interface v0.103 [ 4.363660] virtio_blk virtio1: [vda] 145800 512-byte logical blocks (74.6 MB/71.2 MiB) [ 4.369380] vda: detected capacity change from 0 to 74649600 [ 4.386676] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.389436] vdb: detected capacity change from 0 to 1073741824 [ 4.395505] libphy: Fixed MDIO Bus: probed [ 4.399866] usbcore: registered new interface driver usbserial_generic [ 4.402553] usbserial: USB Serial support registered for generic [ 4.404955] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.409426] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.411171] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.413899] mousedev: PS/2 mouse device common for all mice [ 4.418531] rtc_cmos 00:05: RTC can wake from S4 [ 4.422802] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.423731] rtc_cmos 00:05: registered as rtc0 [ 4.437509] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.439456] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.447754] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.450913] intel_pstate: CPU model not supported [ 4.453637] hid: raw HID events driver (C) Jiri Kosina [ 4.467459] usbcore: registered new interface driver usbhid [ 4.469938] usbhid: USB HID core driver [ 4.471939] drop_monitor: Initializing network drop monitor service [ 4.474474] Initializing XFRM netlink socket [ 4.477371] NET: Registered protocol family 10 [ 4.481825] Segment Routing with IPv6 [ 4.483544] NET: Registered protocol family 17 [ 4.486348] mpls_gso: MPLS GSO support [ 4.493595] RAS: Correctable Errors collector initialized. [ 4.496407] AVX version of gcm_enc/dec engaged. [ 4.497950] AES CTR mode by8 optimization enabled [ 4.607510] sched_clock: Marking stable (4607436133, 0)->(5725693908, -1118257775) [ 4.619744] registered taskstats version 1 [ 4.621907] Loading compiled-in X.509 certificates [ 4.624418] zswap: loaded using pool lzo/zbud [ 4.659542] Key type big_key registered [ 4.676948] Key type encrypted registered [ 4.678765] ima: No TPM chip found, activating TPM-bypass! [ 4.681195] ima: Allocated hash algorithm: sha1 [ 4.683593] ima: No architecture policies found [ 4.685558] evm: Initialising EVM extended attributes: [ 4.691343] evm: security.selinux [ 4.692721] evm: security.ima [ 4.693919] evm: security.capability [ 4.695363] evm: HMAC attrs: 0x1 [ 4.697925] rtc_cmos 00:05: setting system clock to 2026-07-18 02:22:38 UTC (1784341358) [ 4.706771] debug: unmapping init [mem 0xffffffffac003000-0xffffffffac1fffff] [ 4.711441] debug: unmapping init [mem 0xffffffffaad82000-0xffffffffab058fff] [ 4.716051] Write protecting the kernel read-only data: 28672k [ 4.720312] debug: unmapping init [mem 0xffffffffa9403000-0xffffffffa95fffff] [ 4.724398] debug: unmapping init [mem 0xffffffffa9d14000-0xffffffffa9dfffff] [ 4.764290] 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) [ 4.775675] systemd[1]: Detected virtualization kvm. [ 4.777723] systemd[1]: Detected architecture x86-64. [ 4.780586] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.821097] systemd[1]: No hostname configured. [ 4.822922] systemd[1]: Set hostname to . [ 4.825065] random: systemd: uninitialized urandom read (16 bytes read) [ 4.827430] systemd[1]: Initializing machine ID from random generator. [ 4.912192] random: ln: uninitialized urandom read (6 bytes read) [ 5.044367] random: systemd: uninitialized urandom read (16 bytes read) [ 5.047776] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 5.053602] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 5.062386] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Paths. [ OK ] Reached target Slices. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Swap. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.938109] device-mapper: uevent: version 1.0.3 [ 5.940393] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 6.933151] virtio_net virtio0 ens2: renamed from eth0 Starting dracut initqueue hook... [ 7.102844] scsi host0: ata_piix [ 7.111844] scsi host1: ata_piix [ 7.114404] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.120941] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.204298] random: crng init done [ 12.205812] random: 7 urandom warning(s) missed due to ratelimiting [ 13.169760] dracut-initqueue[585]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ 14.046726] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Reached target Remote File Systems. [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 15.614821] printk: systemd: 26 output lines suppressed due to ratelimiting [ 16.190176] SELinux: Disabled at runtime. [ 16.315328] 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) [ 16.325496] systemd[1]: Detected virtualization kvm. [ 16.327873] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 17.208934] systemd[1]: initrd-switch-root.service: Succeeded. [ 17.213393] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 17.217566] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 17.221710] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 17.226456] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 17.235481] systemd[1]: Starting Journal Service... Starting Journal Service... [ 17.241077] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target Slices. [ OK ] Created slice system-getty.slice. Mounting POSIX Message Queue File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Create list of required st…ce nodes for the current kernel... Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target rpc_pipefs.target. Starting Apply Kernel Variables... [ OK ] Listening on RPCbind Server Activation Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target Local Encrypted Volumes. Mounting Huge Pages File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Mounting Kernel Debug File System... [ OK ] Listening on udev Kernel Socket. [ 17.659640] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 18.233771] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 19.125228] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 19.144899] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 19.857398] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 19.914338] EDAC sbridge: Ver: 1.1.2 [ 22.460270] Key type dns_resolver registered [ 22.929612] NFS: Registering the id_resolver key type [ 22.934188] Key type id_resolver registered [ 22.935877] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Load/Save Random Seed. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Restore /run/initramfs on shutdown... Starting Login Service... Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ 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 oleg349-client login: [ 47.808310] libcfs: loading out-of-tree module taints kernel. [ 47.851586] Key type ._llcrypt registered [ 47.857402] Key type .llcrypt registered [ 48.044726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 48.050813] alg: No test for adler32 (adler32-zlib) [ 49.013650] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 49.279851] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 50.880139] Key type lgssc registered [ 51.440225] Lustre: Echo OBD driver; http://www.lustre.org/ [ 109.118521] Lustre: Mounted lustre-client [ 111.452786] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 119.997671] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing check_logdir /tmp/testlogs/ [ 121.426293] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing yml_node [ 122.898892] Lustre: DEBUG MARKER: Client: 2.17.54.173 [ 123.798171] Lustre: DEBUG MARKER: MDS: 2.17.54.173 [ 124.615602] Lustre: DEBUG MARKER: OSS: 2.17.54.173 [ 125.119168] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Jul 17 22:24:38 EDT 2026 [ 130.304146] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 130.759089] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 131.212475] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 141.495083] Lustre: Unmounted lustre-client [ 204.527612] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 206.081635] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 213.227399] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 232.072380] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 22:26:25 (1784341585) [ 242.013584] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 243.793202] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 250.244223] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 260.596669] Lustre: Mounted lustre-client [ 262.355152] Lustre: Unmounted lustre-client [ 271.667380] Key type lgssc unregistered [ 271.776427] LNet: 14630:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 271.778994] LNetError: 14630:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 271.785627] LNet: Removed LNI 192.168.203.49@tcp [ 272.064125] Key type .llcrypt unregistered [ 272.065531] Key type ._llcrypt unregistered [ 276.897687] Key type ._llcrypt registered [ 276.899193] Key type .llcrypt registered [ 277.322857] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 22:27:10 (1784341630) [ 278.079964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 278.091746] alg: No test for adler32 (adler32-zlib) [ 278.945874] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 279.032552] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 280.608154] Key type lgssc registered [ 280.949334] Lustre: Echo OBD driver; http://www.lustre.org/ [ 298.020696] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 299.666990] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 306.071517] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 319.475640] Lustre: Mounted lustre-client [ 319.916150] Lustre: Unmounted lustre-client [ 345.476710] Key type lgssc unregistered [ 345.600655] LNet: 18288:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 345.603701] LNetError: 18288:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 345.613406] LNet: Removed LNI 192.168.203.49@tcp [ 345.857748] Key type .llcrypt unregistered [ 345.859444] Key type ._llcrypt unregistered [ 351.138880] Key type ._llcrypt registered [ 351.140753] Key type .llcrypt registered [ 351.582687] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 22:28:24 (1784341704) [ 352.388965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 352.395684] alg: No test for adler32 (adler32-zlib) [ 353.261918] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 353.351704] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 354.936163] Key type lgssc registered [ 355.324332] Lustre: Echo OBD driver; http://www.lustre.org/ [ 372.195776] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 373.932567] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 382.542583] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 393.199571] Lustre: Mounted lustre-client [ 393.634133] Lustre: Unmounted lustre-client [ 402.782718] Key type lgssc unregistered [ 402.902883] LNet: 21937:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 402.909395] LNetError: 21937:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 402.922843] LNet: Removed LNI 192.168.203.49@tcp [ 403.179577] Key type .llcrypt unregistered [ 403.180739] Key type ._llcrypt unregistered [ 408.227776] Key type ._llcrypt registered [ 408.229333] Key type .llcrypt registered [ 408.691610] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 22:29:21 (1784341761) [ 409.471647] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 409.476973] alg: No test for adler32 (adler32-zlib) [ 410.325874] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 410.417370] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 412.008109] Key type lgssc registered [ 412.388684] Lustre: Echo OBD driver; http://www.lustre.org/ [ 429.969952] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 431.743327] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 438.214387] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 449.014946] Lustre: Mounted lustre-client [ 450.838410] Lustre: Unmounted lustre-client [ 460.325757] Key type lgssc unregistered [ 460.448426] LNet: 25534:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 460.451275] LNetError: 25534:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 460.460672] LNet: Removed LNI 192.168.203.49@tcp [ 460.713995] Key type .llcrypt unregistered [ 460.715585] Key type ._llcrypt unregistered [ 465.442450] Key type ._llcrypt registered [ 465.443801] Key type .llcrypt registered [ 465.887575] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 22:30:19 (1784341819) [ 466.566714] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 466.615639] alg: No test for adler32 (adler32-zlib) [ 467.469237] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 467.554929] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 469.136158] Key type lgssc registered [ 469.488048] Lustre: Echo OBD driver; http://www.lustre.org/ [ 486.300339] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 488.060767] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 494.644262] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 505.327867] Lustre: Mounted lustre-client [ 510.435392] Lustre: lustre-OST0000-osc-ffffa08788950800: Connection to lustre-OST0000 (at 192.168.203.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 522.873769] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 533.179154] Lustre: Unmounted lustre-client [ 550.148795] Key type lgssc unregistered [ 550.259526] LNet: 29143:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 550.262646] LNetError: 29143:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 550.271650] LNet: Removed LNI 192.168.203.49@tcp [ 550.489662] Key type .llcrypt unregistered [ 550.491022] Key type ._llcrypt unregistered [ 554.981590] Key type ._llcrypt registered [ 554.983085] Key type .llcrypt registered [ 555.407567] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 22:31:48 (1784341908) [ 556.077791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 556.103071] alg: No test for adler32 (adler32-zlib) [ 556.947636] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 557.023574] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 558.600165] Key type lgssc registered [ 558.953562] Lustre: Echo OBD driver; http://www.lustre.org/ [ 575.979275] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 577.602376] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 583.729145] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 594.417026] Lustre: Mounted lustre-client [ 599.523496] Lustre: lustre-MDT0000-mdc-ffffa0878a60e800: Connection to lustre-MDT0000 (at 192.168.203.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 604.643080] Lustre: lustre-MDT0001-mdc-ffffa0878a60e800: Connection to lustre-MDT0001 (at 192.168.203.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 610.737896] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 620.896128] Lustre: 30098:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1784341958/real 1784341958] req@000000008f6965d2 x1871018102626304/t0(0) o400->MGC192.168.203.149@tcp@192.168.203.149@tcp:26/25 lens 224/224 e 0 to 1 dl 1784341974 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 620.903882] LustreError: MGC192.168.203.149@tcp: Connection to MGS (at 192.168.203.149@tcp) was lost; in progress operations using this service will fail [ 621.047610] Lustre: Unmounted lustre-client [ 631.347240] Key type lgssc unregistered [ 631.467444] LNet: 32782:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 631.470470] LNetError: 32782:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 631.482938] LNet: Removed LNI 192.168.203.49@tcp [ 631.711762] Key type .llcrypt unregistered [ 631.712801] Key type ._llcrypt unregistered [ 637.540615] Key type ._llcrypt registered [ 637.541796] Key type .llcrypt registered [ 637.975691] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 22:33:11 (1784341991) [ 638.696073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 638.703052] alg: No test for adler32 (adler32-zlib) [ 639.555819] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 639.640235] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 641.224132] Key type lgssc registered [ 641.634666] Lustre: Echo OBD driver; http://www.lustre.org/ [ 701.220169] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 706.464587] LustreError: 34975:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.203.149@tcp: failed processing log, type 1: rc = -110 [ 714.208457] LustreError: 34984:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 864.224500] LustreError: MGC192.168.203.149@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 [ 864.229635] Lustre: Unmounted lustre-client [ 864.234418] LustreError: 34975:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 868.290974] Key type lgssc unregistered [ 868.402311] LNet: 35398:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 868.404461] LNetError: 35398:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 868.418246] LNet: Removed LNI 192.168.203.49@tcp [ 868.632308] Key type .llcrypt unregistered [ 868.633593] Key type ._llcrypt unregistered [ 873.203042] Key type ._llcrypt registered [ 873.204165] Key type .llcrypt registered [ 873.631519] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 22:37:06 (1784342226) [ 874.342371] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 874.353340] alg: No test for adler32 (adler32-zlib) [ 875.196658] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 875.272926] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 876.848147] Key type lgssc registered [ 877.206506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 893.577398] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 895.237677] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 901.255995] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 901.346666] LustreError: 38480:0:(llite_lib.c:1561:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 901.349587] Lustre: Unmounted wrong.lustre-client [ 901.355100] LustreError: 38480:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 918.082728] Key type lgssc unregistered [ 918.187392] LNet: 38895:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 918.189680] LNetError: 38895:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 918.197247] LNet: Removed LNI 192.168.203.49@tcp [ 918.421972] Key type .llcrypt unregistered [ 918.423166] Key type ._llcrypt unregistered [ 922.879848] Key type ._llcrypt registered [ 922.880859] Key type .llcrypt registered [ 923.263567] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 22:37:56 (1784342276) [ 923.929444] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 923.934993] alg: No test for adler32 (adler32-zlib) [ 924.768690] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 924.843118] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 926.416131] Key type lgssc registered [ 926.736827] Lustre: Echo OBD driver; http://www.lustre.org/ [ 985.543870] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 995.613421] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 997.447496] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1005.980200] Lustre: Mounted lustre-client [ 1006.308320] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1016.571207] Lustre: Unmounted lustre-client [ 1033.526746] Key type lgssc unregistered [ 1033.662965] LNet: 42454:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1033.668651] LNetError: 42454:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1033.680802] LNet: Removed LNI 192.168.203.49@tcp [ 1033.964622] Key type .llcrypt unregistered [ 1033.966309] Key type ._llcrypt unregistered [ 1038.988167] Key type ._llcrypt registered [ 1038.989445] Key type .llcrypt registered [ 1039.455073] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 22:39:52 (1784342392) [ 1040.153588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1040.159669] alg: No test for adler32 (adler32-zlib) [ 1041.017833] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 1041.100059] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 1042.680199] Key type lgssc registered [ 1043.079904] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1060.615647] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1062.449502] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1069.044122] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1069.168594] LustreError: 43410:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 1074.272437] LustreError: 45523:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.203.149@tcp: failed processing log, type 1: rc = -110 [ 1079.264062] LustreError: 43410:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 1080.321157] Lustre: Mounted lustre-client [ 1080.706976] Lustre: Unmounted lustre-client [ 1106.151440] Key type lgssc unregistered [ 1106.275427] LNet: 45980:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1106.278137] LNetError: 45980:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1106.285384] LNet: Removed LNI 192.168.203.49@tcp [ 1106.542208] Key type .llcrypt unregistered [ 1106.543391] Key type ._llcrypt unregistered [ 1111.648658] Key type ._llcrypt registered [ 1111.649717] Key type .llcrypt registered [ 1112.108839] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 22:41:05 (1784342465) [ 1112.619316] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 1113.171163] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 22:41:06 (1784342466) [ 1113.607547] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1113.615635] alg: No test for adler32 (adler32-zlib) [ 1114.469387] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 1114.552071] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 1116.128162] Key type lgssc registered [ 1116.498341] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1125.320625] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 22:41:18 (1784342478) [ 1136.670678] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1138.403273] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1144.846660] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1155.571920] Lustre: Mounted lustre-client [ 1160.675942] Lustre: lustre-MDT0000-mdc-ffffa087895de000: Connection to lustre-MDT0000 (at 192.168.203.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1165.794446] LustreError: MGC192.168.203.149@tcp: Connection to MGS (at 192.168.203.149@tcp) was lost; in progress operations using this service will fail [ 1165.804058] Lustre: Evicted from MGS (at 192.168.203.149@tcp) after server handle changed from 0x30f0c5b53f37b354 to 0x30f0c5b53f37b625 [ 1165.807618] Lustre: MGC192.168.203.149@tcp: Connection restored to 192.168.203.149@tcp (at 192.168.203.149@tcp) [ 1168.670888] Lustre: lustre-MDT0000-mdc-ffffa087895de000: Connection restored to 192.168.203.149@tcp (at 192.168.203.149@tcp) [ 1169.013269] Lustre: Unmounted lustre-client [ 1186.010681] Key type lgssc unregistered [ 1186.131466] LNet: 50817:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1186.133942] LNetError: 50817:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1186.141758] LNet: Removed LNI 192.168.203.49@tcp [ 1186.395395] Key type .llcrypt unregistered [ 1186.396599] Key type ._llcrypt unregistered [ 1191.422893] Key type ._llcrypt registered [ 1191.424569] Key type .llcrypt registered [ 1191.931389] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 22:42:25 (1784342545) [ 1192.661195] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1192.682265] alg: No test for adler32 (adler32-zlib) [ 1193.532844] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 1193.616445] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 1195.192109] Key type lgssc registered [ 1195.540274] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1213.287484] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1215.112958] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1221.787935] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1232.370068] Lustre: Mounted lustre-client [ 1237.474431] Lustre: lustre-MDT0000-mdc-ffffa08788d76000: Connection to lustre-MDT0000 (at 192.168.203.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1242.594479] LustreError: MGC192.168.203.149@tcp: Connection to MGS (at 192.168.203.149@tcp) was lost; in progress operations using this service will fail [ 1242.601752] Lustre: Evicted from MGS (at 192.168.203.149@tcp) after server handle changed from 0xaebf194450d148ab to 0xaebf194450d14b8a [ 1242.606500] Lustre: MGC192.168.203.149@tcp: Connection restored to 192.168.203.149@tcp (at 192.168.203.149@tcp) [ 1245.469241] Lustre: lustre-MDT0000-mdc-ffffa08788d76000: Connection restored to 192.168.203.149@tcp (at 192.168.203.149@tcp) [ 1245.796667] Lustre: Unmounted lustre-client [ 1262.903762] Key type lgssc unregistered [ 1263.026526] LNet: 54763:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1263.030644] LNetError: 54763:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1263.040708] LNet: Removed LNI 192.168.203.49@tcp [ 1263.268676] Key type .llcrypt unregistered [ 1263.270159] Key type ._llcrypt unregistered [ 1268.116546] Key type ._llcrypt registered [ 1268.117734] Key type .llcrypt registered [ 1268.565971] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 22:43:41 (1784342621) [ 1268.707201] Key type .llcrypt unregistered [ 1268.709163] Key type ._llcrypt unregistered [ 1270.568508] Key type ._llcrypt registered [ 1270.570499] Key type .llcrypt registered [ 1270.598508] Key type .llcrypt unregistered [ 1270.599774] Key type ._llcrypt unregistered [ 1272.289084] Key type ._llcrypt registered [ 1272.291057] Key type .llcrypt registered [ 1272.742622] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 22:43:46 (1784342626) [ 1273.464509] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1273.510698] alg: No test for adler32 (adler32-zlib) [ 1274.366170] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 1274.448819] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 1276.024081] Key type lgssc registered [ 1276.407108] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1294.302616] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1296.282862] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1303.528237] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1314.290637] Lustre: Mounted lustre-client [ 1315.661233] Lustre: Unmounted lustre-client [ 1315.764298] Lustre: Mounted lustre-client [ 1333.342962] Key type lgssc unregistered [ 1333.449515] LNet: 59169:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1333.453269] LNetError: 59169:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1333.460847] LNet: Removed LNI 192.168.203.49@tcp [ 1333.703378] Key type .llcrypt unregistered [ 1333.704675] Key type ._llcrypt unregistered [ 1338.388933] Key type ._llcrypt registered [ 1338.390470] Key type .llcrypt registered [ 1338.794771] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 22:44:52 (1784342692) [ 1339.441953] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1339.470096] alg: No test for adler32 (adler32-zlib) [ 1340.324462] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 1340.414705] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 1342.000166] Key type lgssc registered [ 1342.367873] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1359.855243] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1361.744243] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1368.253714] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1378.801061] Lustre: Mounted lustre-client [ 1380.195857] Lustre: Unmounted lustre-client [ 1389.522563] Key type lgssc unregistered [ 1389.646415] LNet: 62723:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1389.648807] LNetError: 62723:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1389.660283] LNet: Removed LNI 192.168.203.49@tcp [ 1389.911938] Key type .llcrypt unregistered [ 1389.913569] Key type ._llcrypt unregistered [ 1394.855335] Key type ._llcrypt registered [ 1394.856464] Key type .llcrypt registered [ 1395.305246] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 22:45:48 (1784342748) [ 1395.950688] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1395.958906] alg: No test for adler32 (adler32-zlib) [ 1396.809079] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 1396.893260] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 1398.472149] Key type lgssc registered [ 1398.849582] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1416.849090] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1418.752226] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1425.365522] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1436.146608] Lustre: Mounted lustre-client [ 1437.576269] Lustre: Mounted lustre-client [ 1438.050127] Lustre: Unmounted lustre-client [ 1455.458810] Key type lgssc unregistered [ 1455.570380] LNet: 66366:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1455.573487] LNetError: 66366:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1455.581483] LNet: Removed LNI 192.168.203.49@tcp [ 1455.822802] Key type .llcrypt unregistered [ 1455.824969] Key type ._llcrypt unregistered [ 1460.647489] Key type ._llcrypt registered [ 1460.648627] Key type .llcrypt registered [ 1461.082761] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 22:46:54 (1784342814) [ 1461.763251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1461.829140] alg: No test for adler32 (adler32-zlib) [ 1462.705579] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 1462.801322] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 1464.392150] Key type lgssc registered [ 1464.766357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1524.956593] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1529.713376] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 22:48:02 (1784342882) [ 1540.640373] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1542.718307] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1549.223591] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1559.536490] Lustre: Mounted lustre-client [ 1562.168721] Lustre: Unmounted lustre-client [ 1579.463633] Key type lgssc unregistered [ 1579.588625] LNet: 71116:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1579.592375] LNetError: 71116:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1579.600827] LNet: Removed LNI 192.168.203.49@tcp [ 1579.885413] Key type .llcrypt unregistered [ 1579.886576] Key type ._llcrypt unregistered [ 1585.243021] Key type ._llcrypt registered [ 1585.244056] Key type .llcrypt registered [ 1585.676479] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 22:48:58 (1784342938) [ 1591.502399] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 22:49:04 (1784342944) [ 1592.420913] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1592.430204] alg: No test for adler32 (adler32-zlib) [ 1593.287271] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 1593.374468] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 1594.952217] Key type lgssc registered [ 1595.312613] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1612.800622] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1614.593253] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1621.206654] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1631.732080] Lustre: Mounted lustre-client [ 1633.444830] Lustre: Unmounted lustre-client [ 1642.528861] Key type lgssc unregistered [ 1642.648349] LNet: 75393:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1642.650791] LNetError: 75393:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1642.659738] LNet: Removed LNI 192.168.203.49@tcp [ 1642.894646] Key type .llcrypt unregistered [ 1642.895901] Key type ._llcrypt unregistered [ 1647.139456] Key type ._llcrypt registered [ 1647.141866] Key type .llcrypt registered [ 1647.319799] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1647.324497] alg: No test for adler32 (adler32-zlib) [ 1648.175944] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 1648.268916] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 1649.848145] Key type lgssc registered [ 1650.214302] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1710.594634] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1754.024118] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1755.856917] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1763.652730] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1784.549428] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 22:52:17 (1784343137) [ 1785.621838] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 1816.873434] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1818.660921] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1826.540395] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1852.303750] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1854.073564] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1860.847607] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1871.349527] Lustre: Mounted lustre-client [ 1873.736503] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 1874.036217] Lustre: Unmounted lustre-client [ 1890.920899] Key type lgssc unregistered [ 1891.047637] LNet: 87030:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1891.050802] LNetError: 87030:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1891.058794] LNet: Removed LNI 192.168.203.49@tcp [ 1891.323329] Key type .llcrypt unregistered [ 1891.324708] Key type ._llcrypt unregistered [ 1901.118920] Key type ._llcrypt registered [ 1901.120996] Key type .llcrypt registered [ 1901.233795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1901.239363] alg: No test for adler32 (adler32-zlib) [ 1902.104429] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 1902.198328] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 1903.784190] Key type lgssc registered [ 1904.222004] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1933.749772] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1935.549144] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1943.444432] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1964.197698] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 22:55:17 (1784343317) [ 1974.480927] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1976.299390] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1986.982121] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 22:55:40 (1784343340) [ 2039.931103] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2043.267372] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 22:56:36 (1784343396) [ 2053.395976] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2055.213392] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2061.693980] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2072.050090] Lustre: Mounted lustre-client [ 2072.243619] Lustre: Remounted lustre-client read-only [ 2072.728224] Lustre: Unmounted lustre-client [ 2095.542752] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 22:57:28 (1784343448) [ 2106.288567] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2107.996225] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2114.705907] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2151.032247] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 22:58:24 (1784343504) [ 2209.174245] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2224.410937] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2227.210598] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2251.475304] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 23:00:04 (1784343604) [ 2306.660897] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2321.839275] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2324.574457] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2335.969889] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2374.618497] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 23:02:07 (1784343727) [ 2375.340309] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 2376.041956] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 2376.768973] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 23:02:09 (1784343729) [ 2390.294934] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2392.761354] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2401.416245] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2412.795612] Lustre: Mounted lustre-client [ 2413.262571] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2423.549522] Lustre: Unmounted lustre-client [ 2431.762984] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2431.976428] Lustre: Mounted lustre-client [ 2438.310105] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa0878aff1000.ost_server_uuid 50 [ 2438.992100] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa0878aff1000.ost_server_uuid in FULL state after 0 sec [ 2439.748882] Lustre: Unmounted lustre-client [ 2464.855685] Key type lgssc unregistered [ 2464.983924] LNet: 107330:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2464.990673] LNetError: 107330:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2465.001836] LNet: Removed LNI 192.168.203.49@tcp [ 2465.318482] Key type .llcrypt unregistered [ 2465.320262] Key type ._llcrypt unregistered [ 2471.929182] Key type ._llcrypt registered [ 2471.931090] Key type .llcrypt registered [ 2472.633346] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 23:03:45 (1784343825) [ 2473.762840] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2473.797673] alg: No test for adler32 (adler32-zlib) [ 2474.751304] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 2474.932319] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 2476.536476] Key type lgssc registered [ 2477.238415] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2502.160359] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2504.911502] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2514.261090] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2520.553341] Lustre: Mounted lustre-client [ 2527.810345] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2538.088921] LustreError: MGC192.168.203.149@tcp: Connection to MGS (at 192.168.203.149@tcp) was lost; in progress operations using this service will fail [ 2538.107845] Lustre: Unmounted lustre-client [ 2544.630966] LustreError: lustre-MDT0000-mdc-ffffa087895da000: operation mds_connect to node 192.168.203.149@tcp failed: rc = -16 [ 2549.618230] LustreError: 110704:0:(lmv_obd.c:1468:lmv_statfs()) lustre-MDT0000-mdc-ffffa087895da000: can't stat MDS #0: rc = -16 [ 2549.649168] Lustre: Unmounted lustre-client [ 2549.654295] LustreError: 110704:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 2577.544343] Key type lgssc unregistered [ 2577.689535] LNet: 111158:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2577.694071] LNetError: 111158:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2577.706659] LNet: Removed LNI 192.168.203.49@tcp [ 2577.998247] Key type .llcrypt unregistered [ 2577.999958] Key type ._llcrypt unregistered [ 2584.074106] Key type ._llcrypt registered [ 2584.075649] Key type .llcrypt registered [ 2584.630775] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 23:05:37 (1784343937) [ 2585.511483] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2585.539160] alg: No test for adler32 (adler32-zlib) [ 2586.426614] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 2586.531795] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 2588.128158] Key type lgssc registered [ 2588.612797] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2609.898867] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2612.067503] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2619.994594] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2626.553224] Lustre: Mounted lustre-client [ 2626.913211] Lustre: Unmounted lustre-client [ 2644.717129] Key type lgssc unregistered [ 2644.844972] LNet: 114677:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2644.849602] LNetError: 114677:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2644.858682] LNet: Removed LNI 192.168.203.49@tcp [ 2645.147592] Key type .llcrypt unregistered [ 2645.150598] Key type ._llcrypt unregistered [ 2650.878330] Key type ._llcrypt registered [ 2650.880115] Key type .llcrypt registered [ 2651.418342] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 23:06:44 (1784344004) [ 2652.354165] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 2652.980845] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 23:06:46 (1784344006) [ 2653.953527] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 2654.578451] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 23:06:47 (1784344007) [ 2655.534752] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2655.541313] alg: No test for adler32 (adler32-zlib) [ 2656.417775] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 2656.527898] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 2658.120185] Key type lgssc registered [ 2658.563342] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2679.341888] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2681.648613] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2689.368750] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2700.791154] Lustre: Mounted lustre-client [ 2701.342627] Lustre: Unmounted lustre-client [ 2720.013824] Key type lgssc unregistered [ 2720.137686] LNet: 118856:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2720.142189] LNetError: 118856:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2720.154707] LNet: Removed LNI 192.168.203.49@tcp [ 2720.433036] Key type .llcrypt unregistered [ 2720.434956] Key type ._llcrypt unregistered [ 2726.731286] Key type ._llcrypt registered [ 2726.732724] Key type .llcrypt registered [ 2727.250231] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 23:08:00 (1784344080) [ 2727.663638] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2727.671327] alg: No test for adler32 (adler32-zlib) [ 2728.551289] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 2728.665493] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 2730.264183] Key type lgssc registered [ 2730.744809] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2754.794573] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2756.859322] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2767.386173] Key type lgssc unregistered [ 2767.517648] LNet: 121952:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2767.521971] LNetError: 121952:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2767.531487] LNet: Removed LNI 192.168.203.49@tcp [ 2767.799849] Key type .llcrypt unregistered [ 2767.801501] Key type ._llcrypt unregistered [ 2773.593429] Key type ._llcrypt registered [ 2773.595022] Key type .llcrypt registered [ 2774.092355] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 23:08:47 (1784344127) [ 2777.551899] Key type .llcrypt unregistered [ 2777.554365] Key type ._llcrypt unregistered [ 2780.602241] Key type ._llcrypt registered [ 2780.604089] Key type .llcrypt registered [ 2780.802576] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2780.809793] alg: No test for adler32 (adler32-zlib) [ 2781.695828] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 2781.803780] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 2783.392369] Key type lgssc registered [ 2783.847857] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2846.351256] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2858.691705] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2860.866572] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2882.649605] Key type lgssc unregistered [ 2882.768751] LNet: 125786:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2882.772764] LNetError: 125786:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2882.783447] LNet: Removed LNI 192.168.203.49@tcp [ 2883.060117] Key type .llcrypt unregistered [ 2883.061644] Key type ._llcrypt unregistered [ 2888.931899] Key type ._llcrypt registered [ 2888.933669] Key type .llcrypt registered [ 2889.512295] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 23:10:42 (1784344242) [ 2890.435111] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2890.475965] alg: No test for adler32 (adler32-zlib) [ 2891.360149] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 2891.467301] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 2893.072205] Key type lgssc registered [ 2893.534750] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2914.324975] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2916.475743] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2924.093594] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2929.656819] Lustre: Mounted lustre-client [ 2934.755541] Lustre: lustre-MDT0000-mdc-ffffa0878a98b800: Connection to lustre-MDT0000 (at 192.168.203.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2944.994571] LustreError: MGC192.168.203.149@tcp: Connection to MGS (at 192.168.203.149@tcp) was lost; in progress operations using this service will fail [ 2945.004932] Lustre: Evicted from MGS (at 192.168.203.149@tcp) after server handle changed from 0xe16d1471279407d3 to 0xe16d147127940a0a [ 2945.010147] Lustre: MGC192.168.203.149@tcp: Connection restored to 192.168.203.149@tcp (at 192.168.203.149@tcp) [ 2949.406264] Lustre: lustre-MDT0000-mdc-ffffa0878a98b800: Connection restored to 192.168.203.149@tcp (at 192.168.203.149@tcp) [ 2964.520576] Lustre: Unmounted lustre-client [ 2986.416433] Key type lgssc unregistered [ 2986.615876] LNet: 129804:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2986.620850] LNetError: 129804:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2986.635570] LNet: Removed LNI 192.168.203.49@tcp [ 2987.202397] Key type .llcrypt unregistered [ 2987.204262] Key type ._llcrypt unregistered [ 3000.860292] Key type ._llcrypt registered [ 3000.864807] Key type .llcrypt registered [ 3002.235607] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 23:12:34 (1784344354) [ 3004.655800] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3004.698618] alg: No test for adler32 (adler32-zlib) [ 3005.868891] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 3006.077587] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 3007.744182] Key type lgssc registered [ 3009.110849] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3057.572072] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3060.828545] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3072.216095] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3083.786124] Lustre: Mounted lustre-client [ 3094.549107] Lustre: Unmounted lustre-client [ 3094.789463] Lustre: Mounted lustre-client [ 3103.700145] Lustre: Unmounted lustre-client [ 3125.833670] Key type lgssc unregistered [ 3126.001736] LNet: 134008:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3126.010122] LNetError: 134008:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3126.023065] LNet: Removed LNI 192.168.203.49@tcp [ 3126.417706] Key type .llcrypt unregistered [ 3126.419494] Key type ._llcrypt unregistered [ 3132.466118] Key type ._llcrypt registered [ 3132.468249] Key type .llcrypt registered [ 3133.278649] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 23:14:46 (1784344486) [ 3134.797394] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3134.806231] alg: No test for adler32 (adler32-zlib) [ 3135.777917] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 3135.940102] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 3137.552684] Key type lgssc registered [ 3138.218217] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3169.275534] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3173.084933] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3187.930765] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3190.393691] Lustre: Mounted lustre-client [ 3220.694523] Lustre: Unmounted lustre-client [ 3251.002571] Key type lgssc unregistered [ 3251.258884] LNet: 137402:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3251.268329] LNetError: 137402:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3251.279928] LNet: Removed LNI 192.168.203.49@tcp [ 3251.762711] Key type .llcrypt unregistered [ 3251.766464] Key type ._llcrypt unregistered [ 3262.069830] Key type ._llcrypt registered [ 3262.071546] Key type .llcrypt registered [ 3262.925842] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 23:16:55 (1784344615) [ 3264.454568] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3264.495044] alg: No test for adler32 (adler32-zlib) [ 3265.486253] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 3265.713976] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 3267.352187] Key type lgssc registered [ 3268.164057] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3301.949569] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3305.839812] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3319.484240] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3330.566577] Lustre: Mounted lustre-client [ 3331.815146] Lustre: Unmounted lustre-client [ 3332.057518] Lustre: Mounted lustre-client [ 3336.371710] Lustre: Unmounted lustre-client [ 3358.232770] Key type lgssc unregistered [ 3358.411746] LNet: 141447:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3358.418775] LNetError: 141447:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3358.433027] LNet: Removed LNI 192.168.203.49@tcp [ 3358.936870] Key type .llcrypt unregistered [ 3358.938906] Key type ._llcrypt unregistered [ 3365.748321] Key type ._llcrypt registered [ 3365.750576] Key type .llcrypt registered [ 3366.764885] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 23:18:39 (1784344719) [ 3368.705418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3368.736702] alg: No test for adler32 (adler32-zlib) [ 3369.751923] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 3369.962627] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 3371.609686] Key type lgssc registered [ 3372.521527] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3406.199471] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3410.202391] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3424.676804] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3435.529163] Lustre: Mounted lustre-client [ 3436.967446] Lustre: Unmounted lustre-client [ 3437.370534] Lustre: Mounted lustre-client [ 3442.879186] Lustre: Unmounted lustre-client [ 3464.949823] Key type lgssc unregistered [ 3465.159659] LNet: 145063:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3465.165368] LNetError: 145063:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3465.177358] LNet: Removed LNI 192.168.203.49@tcp [ 3465.679543] Key type .llcrypt unregistered [ 3465.683939] Key type ._llcrypt unregistered [ 3472.857898] Key type ._llcrypt registered [ 3472.862637] Key type .llcrypt registered [ 3473.813861] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 23:20:26 (1784344826) [ 3475.130972] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3475.191460] alg: No test for adler32 (adler32-zlib) [ 3476.135919] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 3476.314659] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 3477.944259] Key type lgssc registered [ 3478.756849] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3510.657108] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3514.309599] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3526.905833] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3528.305571] Lustre: Mounted lustre-client [ 3541.356630] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3542.643734] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3560.973593] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3569.118541] Lustre: Unmounted lustre-client [ 3569.320897] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3569.395792] Lustre: Mounted lustre-client [ 3580.628547] Lustre: Unmounted lustre-client [ 3610.240063] Key type lgssc unregistered [ 3610.411124] LNet: 149129:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3610.416484] LNetError: 149129:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3610.428948] LNet: Removed LNI 192.168.203.49@tcp [ 3610.786623] Key type .llcrypt unregistered [ 3610.787734] Key type ._llcrypt unregistered [ 3628.482932] Key type ._llcrypt registered [ 3628.485468] Key type .llcrypt registered [ 3629.273495] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 23:23:02 (1784344982) [ 3630.761067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3630.820801] alg: No test for adler32 (adler32-zlib) [ 3631.785041] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 3631.932295] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 3633.536709] Key type lgssc registered [ 3634.331456] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3664.399913] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3667.673203] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3679.185386] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3681.693084] Lustre: Mounted lustre-client [ 3818.722566] hrtimer: interrupt took 3018495 ns [ 3848.839733] Lustre: Unmounted lustre-client [ 3849.288580] Lustre: Mounted lustre-client [ 3854.479132] Lustre: Unmounted lustre-client [ 3855.056522] Lustre: Mounted lustre-client [ 3857.602255] Lustre: Unmounted lustre-client [ 3884.601895] Key type lgssc unregistered [ 3884.821068] LNet: 154467:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3884.830367] LNetError: 154467:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3884.849646] LNet: Removed LNI 192.168.203.49@tcp [ 3885.520640] Key type .llcrypt unregistered [ 3885.523973] Key type ._llcrypt unregistered [ 3898.286840] Key type ._llcrypt registered [ 3898.290269] Key type .llcrypt registered [ 3899.537674] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 23:27:31 (1784345251) [ 3901.367661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3901.398495] alg: No test for adler32 (adler32-zlib) [ 3902.456434] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 3902.654315] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 3904.280182] Key type lgssc registered [ 3905.168688] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3944.315311] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3948.714391] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3964.360316] Lustre: DEBUG MARKER: oleg349-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3971.135409] Lustre: Mounted lustre-client [ 3982.781885] Lustre: Unmounted lustre-client [ 3983.205688] Lustre: Mounted lustre-client [ 3984.976200] Lustre: Unmounted lustre-client [ 4009.174935] Key type lgssc unregistered [ 4009.404132] LNet: 158215:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4009.408561] LNetError: 158215:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4009.428311] LNet: Removed LNI 192.168.203.49@tcp [ 4010.039583] Key type .llcrypt unregistered [ 4010.046300] Key type ._llcrypt unregistered [ 4021.708454] Key type ._llcrypt registered [ 4021.710607] Key type .llcrypt registered [ 4022.759561] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 23:29:35 (1784345375) [ 4023.799949] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4023.814280] alg: No test for adler32 (adler32-zlib) [ 4024.853555] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 4025.010371] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 4026.648252] Key type lgssc registered [ 4027.505932] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4046.944733] LustreError: 159910:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 4055.008571] LustreError: 159919:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 4103.840443] LNet: 1 local NIs in recovery (showing 1): 192.168.203.49@tcp [ 4171.424279] LNet: 1 local NIs in recovery (showing 1): 192.168.203.49@tcp [ 4206.561919] 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 [ 4206.588513] Lustre: Unmounted lustre-client [ 4206.593520] LustreError: 159910:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 4217.637892] Key type lgssc unregistered [ 4231.842234] LNet: 1 local NIs in recovery (showing 1): 192.168.203.49@tcp [ 4232.224990] LNet: 160327:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4232.238802] LNetError: 160327:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4301.927805] LNet: Removed LNI 192.168.203.49@tcp [ 4302.542789] Key type .llcrypt unregistered [ 4302.545401] Key type ._llcrypt unregistered [ 4330.980256] Key type ._llcrypt registered [ 4331.013034] Key type .llcrypt registered [ 4331.317227] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4331.328573] alg: No test for adler32 (adler32-zlib) [ 4332.377836] Lustre: Lustre: Build Version: 2.17.54_173_gd3f3fcc [ 4332.620115] LNet: Added LNI 192.168.203.49@tcp [8/256/0/180] [ 4334.272954] Key type lgssc registered [ 4335.496864] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4370.999495] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 4245 sec ========== 23:35:23 (1784345723) [ 4372.506347] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 23:35:24 (1784345724) === [ 4375.172535] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 23:35:27 (1784345727) === [ 4401.731895] Key type lgssc unregistered [ 4401.987172] LNet: 164564:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4401.996250] LNetError: 164564:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4402.013442] LNet: Removed LNI 192.168.203.49@tcp [ 4402.558374] Key type .llcrypt unregistered [ 4402.563355] Key type ._llcrypt unregistered