[ 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 457047773 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002402] x2apic enabled [ 0.003011] Switched APIC routing to physical x2apic. [ 0.004016] kvm-guest: setup PV IPIs [ 0.007343] ..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.008026] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009013] pid_max: default: 32768 minimum: 301 [ 0.010150] LSM: Security Framework initializing [ 0.011061] Yama: becoming mindful. [ 0.012047] SELinux: Initializing. [ 0.013071] *** VALIDATE selinux *** [ 0.022094] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026760] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028076] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029115] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032094] *** VALIDATE tmpfs *** [ 0.033486] *** VALIDATE proc *** [ 0.035031] *** VALIDATE cgroup *** [ 0.036013] *** VALIDATE cgroup2 *** [ 0.038264] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039152] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041031] Spectre V2 : User space: Vulnerable [ 0.042009] Speculative Store Bypass: Vulnerable [ 0.045082] debug: unmapping init [mem 0xffffffff9ca59000-0xffffffff9ca60fff] [ 0.047719] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048521] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049026] ... version: 2 [ 0.050013] ... bit width: 48 [ 0.051012] ... generic registers: 4 [ 0.052013] ... value mask: 0000ffffffffffff [ 0.053012] ... max period: 00007fffffffffff [ 0.054010] ... fixed-purpose events: 3 [ 0.055010] ... event mask: 000000070000000f [ 0.056338] rcu: Hierarchical SRCU implementation. [ 0.058421] smp: Bringing up secondary CPUs ... [ 0.059618] x86: Booting SMP configuration: [ 0.060027] .... node #0, CPUs: #1 #2 #3 [ 0.064142] smp: Brought up 1 node, 4 CPUs [ 0.066016] smpboot: Max logical packages: 1 [ 0.067013] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.228038] node 0 deferred pages initialised in 158ms [ 0.231598] devtmpfs: initialized [ 0.232230] x86/mm: Memory block size: 128MB [ 0.234527] gcov: version magic: 0x41383552 [ 0.236364] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.237087] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.238332] pinctrl core: initialized pinctrl subsystem [ 0.239232] [ 0.239769] ************************************************************* [ 0.240014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.241016] ** ** [ 0.242015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.243025] ** ** [ 0.244035] ** This means that this kernel is built to expose internal ** [ 0.245020] ** IOMMU data structures, which may compromise security on ** [ 0.246012] ** your system. ** [ 0.247022] ** ** [ 0.248024] ** If you see this message and you are not debugging the ** [ 0.249020] ** kernel, report this immediately to your vendor! ** [ 0.250030] ** ** [ 0.251014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.252016] ************************************************************* [ 0.253935] NET: Registered protocol family 16 [ 0.254524] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.255069] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.256080] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.257559] cpuidle: using governor menu [ 0.259727] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.262476] PCI: Using configuration type 1 for base access [ 0.265326] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.274121] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.275049] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.277123] cryptd: max_cpu_qlen set to 1000 [ 0.280207] ACPI: Added _OSI(Module Device) [ 0.282022] ACPI: Added _OSI(Processor Device) [ 0.283012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.284012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.289256] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.296544] ACPI: Interpreter enabled [ 0.297112] ACPI: PM: (supports S0 S3 S4 S5) [ 0.298019] ACPI: Using IOAPIC for interrupt routing [ 0.301216] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.304523] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.314691] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.317056] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.320025] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.323103] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.327456] acpiphp: Slot [2] registered [ 0.328139] acpiphp: Slot [5] registered [ 0.329158] acpiphp: Slot [6] registered [ 0.330129] acpiphp: Slot [3] registered [ 0.332153] acpiphp: Slot [4] registered [ 0.333140] acpiphp: Slot [7] registered [ 0.335142] acpiphp: Slot [8] registered [ 0.337114] acpiphp: Slot [9] registered [ 0.338240] acpiphp: Slot [10] registered [ 0.340115] acpiphp: Slot [11] registered [ 0.341163] acpiphp: Slot [12] registered [ 0.342121] acpiphp: Slot [13] registered [ 0.344123] acpiphp: Slot [14] registered [ 0.345128] acpiphp: Slot [15] registered [ 0.347108] acpiphp: Slot [16] registered [ 0.348072] acpiphp: Slot [17] registered [ 0.349121] acpiphp: Slot [18] registered [ 0.350136] acpiphp: Slot [19] registered [ 0.352120] acpiphp: Slot [20] registered [ 0.353129] acpiphp: Slot [21] registered [ 0.356171] acpiphp: Slot [22] registered [ 0.358106] acpiphp: Slot [23] registered [ 0.359106] acpiphp: Slot [24] registered [ 0.360097] acpiphp: Slot [25] registered [ 0.362102] acpiphp: Slot [26] registered [ 0.363098] acpiphp: Slot [27] registered [ 0.364133] acpiphp: Slot [28] registered [ 0.365130] acpiphp: Slot [29] registered [ 0.366102] acpiphp: Slot [30] registered [ 0.368097] acpiphp: Slot [31] registered [ 0.369098] PCI host bridge to bus 0000:00 [ 0.370021] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.372032] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.374022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.376023] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.378029] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.380032] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.382223] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.385062] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.387255] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.395024] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.399135] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.402036] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.403041] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.405029] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.408085] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.411182] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.413052] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.416966] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.420986] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.429774] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.434021] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.438000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.442919] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.448019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.463026] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.473959] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.481020] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.487020] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.511022] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.520541] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.523394] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.525397] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.526334] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.528205] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.534172] iommu: Default domain type: Passthrough [ 0.535472] SCSI subsystem initialized [ 0.537129] ACPI: bus type USB registered [ 0.538118] usbcore: registered new interface driver usbfs [ 0.540161] usbcore: registered new interface driver hub [ 0.542078] usbcore: registered new device driver usb [ 0.544191] pps_core: LinuxPPS API ver. 1 registered [ 0.545011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.548062] PTP clock support registered [ 0.551046] EDAC MC: Ver: 3.0.0 [ 0.552429] PCI: Using ACPI for IRQ routing [ 0.554862] NetLabel: Initializing [ 0.556017] NetLabel: domain hash size = 128 [ 0.558012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.560091] NetLabel: unlabeled traffic allowed by default [ 0.562152] vgaarb: loaded [ 0.564351] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.566016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.572797] clocksource: Switched to clocksource kvm-clock [ 0.684987] VFS: Disk quotas dquot_6.6.0 [ 0.686802] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.689562] *** VALIDATE ramfs *** [ 0.691310] *** VALIDATE hugetlbfs *** [ 0.693455] pnp: PnP ACPI init [ 0.696298] pnp: PnP ACPI: found 6 devices [ 0.724607] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.728307] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.730726] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.733125] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.735620] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.738337] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.740842] NET: Registered protocol family 2 [ 0.742722] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.747193] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.750959] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.756107] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.759424] TCP: Hash tables configured (established 65536 bind 65536) [ 0.761767] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.765096] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.767877] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.771072] NET: Registered protocol family 1 [ 0.773665] RPC: Registered named UNIX socket transport module. [ 0.775485] RPC: Registered udp transport module. [ 0.777866] RPC: Registered tcp transport module. [ 0.779270] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.781932] NET: Registered protocol family 44 [ 0.783637] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.785977] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.787900] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.790166] PCI: CLS 0 bytes, default 64 [ 0.792149] Unpacking initramfs... [ 2.214919] debug: unmapping init [mem 0xffff98eafcc64000-0xffff98eafffcffff] [ 2.219916] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.222328] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.225496] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.728757] Initialise system trusted keyrings [ 2.730212] Key type blacklist registered [ 2.732697] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.741685] zbud: loaded [ 2.744843] *** VALIDATE nfs *** [ 2.746418] *** VALIDATE nfs4 *** [ 2.748132] pstore: using deflate compression [ 2.751297] Platform Keyring initialized [ 2.864636] NET: Registered protocol family 38 [ 2.866860] Key type asymmetric registered [ 2.868725] Asymmetric key parser 'x509' registered [ 2.870783] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.874083] io scheduler mq-deadline registered [ 2.875750] io scheduler kyber registered [ 2.877351] io scheduler bfq registered [ 2.879551] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.882048] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.886474] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.889575] ACPI: Power Button [PWRF] [ 2.895159] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.902346] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.915747] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.944600] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.976414] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.982200] Non-volatile memory driver v1.3 [ 2.984031] Linux agpgart interface v0.103 [ 3.020843] virtio_blk virtio1: [vda] 146016 512-byte logical blocks (74.8 MB/71.3 MiB) [ 3.023519] vda: detected capacity change from 0 to 74760192 [ 3.039220] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.042126] vdb: detected capacity change from 0 to 1073741824 [ 3.052710] libphy: Fixed MDIO Bus: probed [ 3.058555] usbcore: registered new interface driver usbserial_generic [ 3.061423] usbserial: USB Serial support registered for generic [ 3.064213] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.069396] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.071507] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.074366] mousedev: PS/2 mouse device common for all mice [ 3.077370] rtc_cmos 00:05: RTC can wake from S4 [ 3.080165] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.080499] rtc_cmos 00:05: registered as rtc0 [ 3.085333] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.089214] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.089852] intel_pstate: CPU model not supported [ 3.096103] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.096381] hid: raw HID events driver (C) Jiri Kosina [ 3.101067] usbcore: registered new interface driver usbhid [ 3.103013] usbhid: USB HID core driver [ 3.104725] drop_monitor: Initializing network drop monitor service [ 3.107421] Initializing XFRM netlink socket [ 3.109594] NET: Registered protocol family 10 [ 3.112694] Segment Routing with IPv6 [ 3.114423] NET: Registered protocol family 17 [ 3.116655] mpls_gso: MPLS GSO support [ 3.123649] RAS: Correctable Errors collector initialized. [ 3.125579] AVX version of gcm_enc/dec engaged. [ 3.127055] AES CTR mode by8 optimization enabled [ 3.203346] sched_clock: Marking stable (3203302937, 0)->(4169449979, -966147042) [ 3.208620] registered taskstats version 1 [ 3.210608] Loading compiled-in X.509 certificates [ 3.212579] zswap: loaded using pool lzo/zbud [ 3.243129] Key type big_key registered [ 3.256076] Key type encrypted registered [ 3.257749] ima: No TPM chip found, activating TPM-bypass! [ 3.259727] ima: Allocated hash algorithm: sha1 [ 3.261692] ima: No architecture policies found [ 3.263590] evm: Initialising EVM extended attributes: [ 3.265687] evm: security.selinux [ 3.266641] evm: security.ima [ 3.267408] evm: security.capability [ 3.269584] evm: HMAC attrs: 0x1 [ 3.273261] rtc_cmos 00:05: setting system clock to 2026-08-31 05:23:25 UTC (1788153805) [ 3.279950] debug: unmapping init [mem 0xffffffff9da03000-0xffffffff9dbfffff] [ 3.282862] debug: unmapping init [mem 0xffffffff9c782000-0xffffffff9ca58fff] [ 3.292183] Write protecting the kernel read-only data: 28672k [ 3.296164] debug: unmapping init [mem 0xffffffff9ae03000-0xffffffff9affffff] [ 3.299022] debug: unmapping init [mem 0xffffffff9b714000-0xffffffff9b7fffff] [ 3.341984] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.351269] systemd[1]: Detected virtualization kvm. [ 3.352872] systemd[1]: Detected architecture x86-64. [ 3.354507] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.383611] systemd[1]: No hostname configured. [ 3.385115] systemd[1]: Set hostname to . [ 3.386934] random: systemd: uninitialized urandom read (16 bytes read) [ 3.388894] systemd[1]: Initializing machine ID from random generator. [ 3.430881] random: ln: uninitialized urandom read (6 bytes read) [ 3.527699] random: systemd: uninitialized urandom read (16 bytes read) [ 3.531299] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.534663] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.538837] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Paths. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Reached target Local Encrypted Volumes. Starting Setup Virtual Console... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.109792] device-mapper: uevent: version 1.0.3 [ 4.112306] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.878858] virtio_net virtio0 ens2: renamed from eth0 [ 4.965940] scsi host0: ata_piix [ 5.107291] scsi host1: ata_piix [ 5.109559] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.112356] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.815866] dracut-initqueue[582]: RTNETLINK answers: File exists [ 9.782140] random: crng init done [ 9.783840] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.348611] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.555967] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.837650] SELinux: Disabled at runtime. [ 11.899633] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.906890] systemd[1]: Detected virtualization kvm. [ 11.908976] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.480352] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.484307] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.490128] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.494446] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.498480] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.509122] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.514335] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. Mounting Huge Pages File System... Mounting POSIX Message Queue File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. Starting Remount Root and Kernel File Systems... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. Mounting Kernel Debug File System... [ 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. Starting Apply Kernel Variables... [ OK ] Stopped target Initrd Root File System. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-getty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. Activating swap /dev/disk/by-label/SWAP... [ OK [[ 12.688562] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS 0m] Listening on Process Core Dump Socket. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ 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 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 ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 13.038731] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.376718] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.406523] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.504282] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.530227] EDAC sbridge: Ver: 1.1.2 [ 14.903543] Key type dns_resolver registered [ 15.207231] NFS: Registering the id_resolver key type [ 15.209639] Key type id_resolver registered [ 15.210914] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting 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 irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ 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... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ 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 oleg403-client login: [ 42.054774] libcfs: loading out-of-tree module taints kernel. [ 42.158659] Key type ._llcrypt registered [ 42.164340] Key type .llcrypt registered [ 42.433471] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 42.441534] alg: No test for adler32 (adler32-zlib) [ 43.497090] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 43.863202] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 45.504177] Key type lgssc registered [ 46.329108] Lustre: Echo OBD driver; http://www.lustre.org/ [ 207.345092] Lustre: Mounted lustre-client [ 213.098576] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 226.766926] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing check_logdir /tmp/testlogs/ [ 232.562227] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing yml_node [ 232.929952] Lustre: lustre-OST0000-osc-ffff98eb4709b800: disconnect after 23s idle [ 237.850580] Lustre: DEBUG MARKER: Client: 2.17.55.27 [ 240.594769] Lustre: DEBUG MARKER: MDS: 2.17.55.27 [ 243.223053] Lustre: DEBUG MARKER: OSS: 2.17.55.27 [ 245.081437] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Aug 31 01:27:25 EDT 2026 [ 260.134742] hrtimer: interrupt took 4241547 ns [ 265.371386] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 267.283492] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 270.246675] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 280.633820] Lustre: Unmounted lustre-client [ 398.032762] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 404.810957] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 431.810636] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 484.550705] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 01:31:24 (1788154284) [ 524.084249] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 530.051941] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 553.562548] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 554.355680] Lustre: Mounted lustre-client [ 559.356538] Lustre: Unmounted lustre-client [ 598.898322] Key type lgssc unregistered [ 599.215681] LNet: 14675:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 599.225832] LNetError: 14675:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 599.242486] LNet: Removed LNI 192.168.204.3@tcp [ 600.291166] Key type .llcrypt unregistered [ 600.292920] Key type ._llcrypt unregistered [ 617.444863] Key type ._llcrypt registered [ 617.447428] Key type .llcrypt registered [ 618.955676] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 01:33:39 (1788154419) [ 621.897111] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 621.982918] alg: No test for adler32 (adler32-zlib) [ 623.059695] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 623.405949] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 625.200717] Key type lgssc registered [ 626.675853] Lustre: Echo OBD driver; http://www.lustre.org/ [ 683.414569] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 690.205786] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 714.566791] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 726.298674] Lustre: Mounted lustre-client [ 729.127421] Lustre: Unmounted lustre-client [ 758.214505] Key type lgssc unregistered [ 758.665782] LNet: 18349:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 758.678349] LNetError: 18349:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 758.695846] LNet: Removed LNI 192.168.204.3@tcp [ 759.758578] Key type .llcrypt unregistered [ 759.763189] Key type ._llcrypt unregistered [ 779.727883] Key type ._llcrypt registered [ 779.732447] Key type .llcrypt registered [ 781.344694] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 01:36:21 (1788154581) [ 784.197194] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 784.262644] alg: No test for adler32 (adler32-zlib) [ 785.461087] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 785.923865] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 787.616463] Key type lgssc registered [ 788.594997] Lustre: Echo OBD driver; http://www.lustre.org/ [ 846.310770] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 852.559930] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 883.339332] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 888.914914] Lustre: Mounted lustre-client [ 892.226418] Lustre: Unmounted lustre-client [ 930.717313] Key type lgssc unregistered [ 931.059185] LNet: 22008:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 931.068568] LNetError: 22008:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 931.096174] LNet: Removed LNI 192.168.204.3@tcp [ 931.831499] Key type .llcrypt unregistered [ 931.836034] Key type ._llcrypt unregistered [ 946.886774] Key type ._llcrypt registered [ 946.888730] Key type .llcrypt registered [ 948.030260] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 01:39:08 (1788154748) [ 950.304789] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 950.321600] alg: No test for adler32 (adler32-zlib) [ 951.454761] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 951.696969] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 953.344186] Key type lgssc registered [ 954.277236] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1002.115378] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1007.071768] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1027.732387] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1029.659597] Lustre: Mounted lustre-client [ 1034.113173] Lustre: Unmounted lustre-client [ 1061.549741] Key type lgssc unregistered [ 1061.762885] LNet: 25627:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1061.772974] LNetError: 25627:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1061.789294] LNet: Removed LNI 192.168.204.3@tcp [ 1062.496803] Key type .llcrypt unregistered [ 1062.503171] Key type ._llcrypt unregistered [ 1077.310648] Key type ._llcrypt registered [ 1077.314441] Key type .llcrypt registered [ 1078.491837] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 01:41:19 (1788154879) [ 1080.752585] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1080.780127] alg: No test for adler32 (adler32-zlib) [ 1081.843088] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1082.035510] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 1083.696667] Key type lgssc registered [ 1084.833508] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1137.985996] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1145.027108] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1168.997156] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1171.797380] Lustre: Mounted lustre-client [ 1177.066930] Lustre: lustre-OST0000-osc-ffff98eb50ed5800: Connection to lustre-OST0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1195.740565] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1206.060743] Lustre: Unmounted lustre-client [ 1234.711408] Key type lgssc unregistered [ 1235.060866] LNet: 29253:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1235.091584] LNetError: 29253:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1235.130270] LNet: Removed LNI 192.168.204.3@tcp [ 1236.082267] Key type .llcrypt unregistered [ 1236.084768] Key type ._llcrypt unregistered [ 1251.923183] Key type ._llcrypt registered [ 1251.924712] Key type .llcrypt registered [ 1253.352696] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 01:44:13 (1788155053) [ 1256.109971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1256.149444] alg: No test for adler32 (adler32-zlib) [ 1257.213508] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1257.509414] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 1259.160448] Key type lgssc registered [ 1260.582637] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1313.966607] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1320.687728] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1342.807809] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1345.481587] Lustre: Mounted lustre-client [ 1355.752929] Lustre: lustre-MDT0000-mdc-ffff98eb58870000: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1366.002133] Lustre: lustre-MDT0001-mdc-ffff98eb58870000: Connection to lustre-MDT0001 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1371.696626] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1377.248175] Lustre: 30227:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788155163/real 1788155163] req@00000000da7179b9 x1875016479152384/t0(0) o400->MGC192.168.204.103@tcp@192.168.204.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1788155179 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1377.268867] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [ 1382.077254] Lustre: Unmounted lustre-client [ 1399.247480] Key type lgssc unregistered [ 1399.484080] LNet: 32927:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1399.492181] LNetError: 32927:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1399.510942] LNet: Removed LNI 192.168.204.3@tcp [ 1400.321501] Key type .llcrypt unregistered [ 1400.329078] Key type ._llcrypt unregistered [ 1416.651606] Key type ._llcrypt registered [ 1416.654927] Key type .llcrypt registered [ 1417.903839] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 01:46:58 (1788155218) [ 1420.540581] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1420.608515] alg: No test for adler32 (adler32-zlib) [ 1421.710763] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1421.961630] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 1423.672839] Key type lgssc registered [ 1424.746439] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1508.401365] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1514.144727] LustreError: 35129:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.204.103@tcp: failed processing log, type 1: rc = -110 [ 1524.192651] LustreError: 35139:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1671.136748] LustreError: MGC192.168.204.103@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 [ 1671.166274] Lustre: Unmounted lustre-client [ 1671.171679] LustreError: 35129:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1683.525626] Key type lgssc unregistered [ 1683.906692] LNet: 35555:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1683.919190] LNetError: 35555:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1683.936798] LNet: Removed LNI 192.168.204.3@tcp [ 1684.769886] Key type .llcrypt unregistered [ 1684.772393] Key type ._llcrypt unregistered [ 1699.253491] Key type ._llcrypt registered [ 1699.255639] Key type .llcrypt registered [ 1700.638757] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 01:51:41 (1788155501) [ 1702.890092] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1702.929735] alg: No test for adler32 (adler32-zlib) [ 1703.981667] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1704.217652] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 1705.920275] Key type lgssc registered [ 1706.975149] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1754.759574] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1761.193297] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1781.937908] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1782.358514] LustreError: 38647:0:(llite_lib.c:1564:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1782.366223] Lustre: Unmounted wrong.lustre-client [ 1782.379469] LustreError: 38647:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1810.103391] Key type lgssc unregistered [ 1810.414621] LNet: 39063:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1810.422380] LNetError: 39063:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1810.443277] LNet: Removed LNI 192.168.204.3@tcp [ 1811.301534] Key type .llcrypt unregistered [ 1811.305065] Key type ._llcrypt unregistered [ 1824.606487] Key type ._llcrypt registered [ 1824.608624] Key type .llcrypt registered [ 1825.805506] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 01:53:46 (1788155626) [ 1828.090977] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1828.169391] alg: No test for adler32 (adler32-zlib) [ 1829.246834] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1829.509777] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 1831.177496] Key type lgssc registered [ 1832.300765] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1916.126575] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1955.222478] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1962.206993] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1984.051538] Lustre: Mounted lustre-client [ 1986.128890] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1996.443122] Lustre: Unmounted lustre-client [ 2024.177580] Key type lgssc unregistered [ 2024.472156] LNet: 42640:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2024.486701] LNetError: 42640:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2024.508666] LNet: Removed LNI 192.168.204.3@tcp [ 2025.208110] Key type .llcrypt unregistered [ 2025.214935] Key type ._llcrypt unregistered [ 2039.717233] Key type ._llcrypt registered [ 2039.719780] Key type .llcrypt registered [ 2040.854478] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 01:57:21 (1788155841) [ 2042.580689] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2042.596264] alg: No test for adler32 (adler32-zlib) [ 2043.565544] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2043.811925] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 2045.464362] Key type lgssc registered [ 2046.553674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2097.898836] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2103.598626] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2125.117656] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2126.690915] LustreError: 43598:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2130.784639] LustreError: 45722:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.204.103@tcp: failed processing log, type 1: rc = -110 [ 2136.792181] LustreError: 43598:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2138.181453] Lustre: Mounted lustre-client [ 2140.347516] Lustre: Unmounted lustre-client [ 2178.907060] Key type lgssc unregistered [ 2179.137259] LNet: 46183:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2179.144211] LNetError: 46183:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2180.199623] LNet: Removed LNI 192.168.204.3@tcp [ 2180.960656] Key type .llcrypt unregistered [ 2180.970198] Key type ._llcrypt unregistered [ 2197.150092] Key type ._llcrypt registered [ 2197.152528] Key type .llcrypt registered [ 2198.724547] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 01:59:59 (1788155999) [ 2200.578735] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2202.419200] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 02:00:03 (1788156003) [ 2203.941436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2203.970626] alg: No test for adler32 (adler32-zlib) [ 2204.997976] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2205.212589] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 2206.896388] Key type lgssc registered [ 2208.054775] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2231.913702] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 02:00:32 (1788156032) [ 2267.272095] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2273.412633] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2294.760402] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2296.689943] Lustre: Mounted lustre-client [ 2307.051259] Lustre: lustre-MDT0000-mdc-ffff98eb46504800: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2322.431219] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [ 2322.465772] Lustre: Evicted from MGS (at 192.168.204.103@tcp) after server handle changed from 0xa65938559da47527 to 0xa65938559da477a4 [ 2322.492185] Lustre: MGC192.168.204.103@tcp: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 2327.298928] Lustre: lustre-MDT0000-mdc-ffff98eb46504800: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 2335.199270] Lustre: Unmounted lustre-client [ 2371.795945] Key type lgssc unregistered [ 2372.156483] LNet: 51060:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2372.171880] LNetError: 51060:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2372.203302] LNet: Removed LNI 192.168.204.3@tcp [ 2372.897153] Key type .llcrypt unregistered [ 2372.899537] Key type ._llcrypt unregistered [ 2388.321211] Key type ._llcrypt registered [ 2388.325673] Key type .llcrypt registered [ 2389.510308] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 02:03:10 (1788156190) [ 2391.779714] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2391.868390] alg: No test for adler32 (adler32-zlib) [ 2393.005500] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2393.277164] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 2394.952704] Key type lgssc registered [ 2396.306855] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2447.347266] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2453.674583] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2476.224126] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2478.870422] Lustre: Mounted lustre-client [ 2489.389945] Lustre: lustre-MDT0000-mdc-ffff98eb494a1000: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2505.760464] Lustre: 52024:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788156291/real 1788156291] req@00000000001a1438 x1875017670332672/t0(0) o400->MGC192.168.204.103@tcp@192.168.204.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1788156307 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2505.791972] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [ 2505.826579] Lustre: Evicted from MGS (at 192.168.204.103@tcp) after server handle changed from 0x81d732b02e3edf0f to 0x81d732b02e3ee012 [ 2505.839296] Lustre: MGC192.168.204.103@tcp: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 2511.639802] Lustre: lustre-MDT0000-mdc-ffff98eb494a1000: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 2516.228624] Lustre: Unmounted lustre-client [ 2543.056988] Key type lgssc unregistered [ 2543.304710] LNet: 55026:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2543.311541] LNetError: 55026:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2543.326936] LNet: Removed LNI 192.168.204.3@tcp [ 2543.980626] Key type .llcrypt unregistered [ 2543.984471] Key type ._llcrypt unregistered [ 2558.788701] Key type ._llcrypt registered [ 2558.793203] Key type .llcrypt registered [ 2560.028281] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 02:06:00 (1788156360) [ 2560.547505] Key type .llcrypt unregistered [ 2560.550416] Key type ._llcrypt unregistered [ 2566.820625] Key type ._llcrypt registered [ 2566.822806] Key type .llcrypt registered [ 2566.879804] Key type .llcrypt unregistered [ 2566.885652] Key type ._llcrypt unregistered [ 2571.828771] Key type ._llcrypt registered [ 2571.830721] Key type .llcrypt registered [ 2573.220206] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 02:06:13 (1788156373) [ 2576.425074] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2576.467722] alg: No test for adler32 (adler32-zlib) [ 2577.718394] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2578.011879] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 2579.793326] Key type lgssc registered [ 2581.213907] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2631.410226] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2637.694529] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2659.099189] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2660.862625] Lustre: Mounted lustre-client [ 2666.189458] Lustre: Unmounted lustre-client [ 2666.510470] Lustre: Mounted lustre-client [ 2668.519238] Lustre: Unmounted lustre-client [ 2695.600307] Key type lgssc unregistered [ 2695.943436] LNet: 59444:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2695.958666] LNetError: 59444:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2695.983515] LNet: Removed LNI 192.168.204.3@tcp [ 2696.708461] Key type .llcrypt unregistered [ 2696.711311] Key type ._llcrypt unregistered [ 2711.832344] Key type ._llcrypt registered [ 2711.834725] Key type .llcrypt registered [ 2713.197154] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 02:08:33 (1788156513) [ 2716.085054] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2716.107582] alg: No test for adler32 (adler32-zlib) [ 2717.373302] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2717.631831] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 2719.384262] Key type lgssc registered [ 2720.617979] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2777.175812] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2783.194544] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2805.089203] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2807.853075] Lustre: Mounted lustre-client [ 2810.254736] Lustre: Unmounted lustre-client [ 2837.528812] Key type lgssc unregistered [ 2837.875865] LNet: 63009:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2837.893609] LNetError: 63009:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2837.912610] LNet: Removed LNI 192.168.204.3@tcp [ 2838.840842] Key type .llcrypt unregistered [ 2838.845337] Key type ._llcrypt unregistered [ 2854.881205] Key type ._llcrypt registered [ 2854.883553] Key type .llcrypt registered [ 2856.573123] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 02:10:56 (1788156656) [ 2858.958130] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2858.986970] alg: No test for adler32 (adler32-zlib) [ 2860.138309] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2860.477536] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 2862.160198] Key type lgssc registered [ 2863.319786] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2920.180662] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2926.884530] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2953.937218] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2959.952949] Lustre: Mounted lustre-client [ 2962.450623] Lustre: Mounted lustre-client [ 2965.345575] Lustre: Unmounted lustre-client [ 2967.262774] Lustre: Unmounted lustre-client [ 3005.016429] Key type lgssc unregistered [ 3005.324356] LNet: 66671:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3005.332989] LNetError: 66671:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3005.356346] LNet: Removed LNI 192.168.204.3@tcp [ 3006.466179] Key type .llcrypt unregistered [ 3006.470685] Key type ._llcrypt unregistered [ 3024.648340] Key type ._llcrypt registered [ 3024.653821] Key type .llcrypt registered [ 3026.288538] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 02:13:46 (1788156826) [ 3029.394217] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3029.420133] alg: No test for adler32 (adler32-zlib) [ 3030.585287] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3031.014155] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 3032.728165] Key type lgssc registered [ 3033.794808] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3121.937684] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3139.342849] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 02:15:40 (1788156940) [ 3180.092419] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3186.525805] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3213.418500] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3220.542215] Lustre: Mounted lustre-client [ 3228.748652] Lustre: Unmounted lustre-client [ 3257.456591] Key type lgssc unregistered [ 3257.841629] LNet: 71441:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3257.851142] LNetError: 71441:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3258.922349] LNet: Removed LNI 192.168.204.3@tcp [ 3259.957345] Key type .llcrypt unregistered [ 3259.962297] Key type ._llcrypt unregistered [ 3276.650743] Key type ._llcrypt registered [ 3276.655556] Key type .llcrypt registered [ 3278.514262] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 02:17:58 (1788157078) [ 3300.075698] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 02:18:20 (1788157100) [ 3303.031703] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3303.058846] alg: No test for adler32 (adler32-zlib) [ 3304.252896] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3304.631410] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 3306.440243] Key type lgssc registered [ 3307.914602] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3363.382785] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3373.425967] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3397.434813] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3403.315898] Lustre: Mounted lustre-client [ 3410.382370] Lustre: Unmounted lustre-client [ 3442.574652] Key type lgssc unregistered [ 3443.019973] LNet: 75733:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3443.044016] LNetError: 75733:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3444.074542] LNet: Removed LNI 192.168.204.3@tcp [ 3445.091787] Key type .llcrypt unregistered [ 3445.096777] Key type ._llcrypt unregistered [ 3463.787094] Key type ._llcrypt registered [ 3463.817577] Key type .llcrypt registered [ 3464.258304] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3464.314129] alg: No test for adler32 (adler32-zlib) [ 3465.901096] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3466.578201] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 3468.482078] Key type lgssc registered [ 3470.862531] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3562.349501] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3708.618553] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3715.983588] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3737.414808] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3780.751918] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 02:26:21 (1788157581) [ 3784.168942] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3884.560545] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3890.869714] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3915.475140] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3993.324588] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4000.775621] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4026.765992] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4034.210591] Lustre: Mounted lustre-client [ 4039.430969] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 4041.938876] Lustre: Unmounted lustre-client [ 4070.355242] Key type lgssc unregistered [ 4070.627870] LNet: 87439:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4070.641270] LNetError: 87439:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4070.671672] LNet: Removed LNI 192.168.204.3@tcp [ 4071.467847] Key type .llcrypt unregistered [ 4071.477268] Key type ._llcrypt unregistered [ 4106.816503] Key type ._llcrypt registered [ 4106.829299] Key type .llcrypt registered [ 4107.433767] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4107.449648] alg: No test for adler32 (adler32-zlib) [ 4108.579403] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 4108.841748] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 4110.658575] Key type lgssc registered [ 4112.459120] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4191.302486] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4198.447599] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4229.442707] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4273.436549] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 02:34:34 (1788158074) [ 4307.720544] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4313.732715] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4335.704783] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 02:35:36 (1788158136) [ 4406.867728] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4418.057285] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 02:36:58 (1788158218) [ 4460.210994] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4466.706577] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4488.564222] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4500.038665] Lustre: Mounted lustre-client [ 4500.892544] Lustre: Remounted lustre-client read-only [ 4501.428251] Lustre: Remounted lustre-client read-write [ 4504.965919] Lustre: Unmounted lustre-client [ 4542.526725] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 02:39:02 (1788158342) [ 4578.450066] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4584.363801] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4605.621371] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4646.076683] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 02:40:46 (1788158446) [ 4714.409344] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4753.214954] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4760.145856] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4816.048724] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 02:43:36 (1788158616) [ 4885.754437] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4919.615284] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4925.495835] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4949.033217] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5026.291409] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 02:47:07 (1788158827) [ 5027.991086] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 5029.581915] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 5031.566505] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 02:47:12 (1788158832) [ 5065.901931] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5072.251922] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5093.300763] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5115.191331] Lustre: Mounted lustre-client [ 5117.528123] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5127.917531] Lustre: Unmounted lustre-client [ 5150.798554] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5151.367240] Lustre: Mounted lustre-client [ 5169.684887] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff98eb494a1800.ost_server_uuid 50 [ 5171.508106] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff98eb494a1800.ost_server_uuid in FULL state after 0 sec [ 5175.452251] Lustre: Unmounted lustre-client [ 5228.326028] Key type lgssc unregistered [ 5228.746380] LNet: 107825:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5228.753857] LNetError: 107825:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5228.773674] LNet: Removed LNI 192.168.204.3@tcp [ 5229.364604] Key type .llcrypt unregistered [ 5229.367929] Key type ._llcrypt unregistered [ 5250.982304] Key type ._llcrypt registered [ 5250.984163] Key type .llcrypt registered [ 5252.088618] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 02:50:53 (1788159053) [ 5255.600504] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5255.616912] alg: No test for adler32 (adler32-zlib) [ 5256.884180] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 5257.248585] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 5258.992459] Key type lgssc registered [ 5260.445255] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5317.287692] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5323.882271] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5347.383811] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5358.664678] Lustre: Mounted lustre-client [ 5363.695352] Lustre: lustre-MDT0000-mdc-ffff98eb46500000: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5365.964077] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5376.286811] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [ 5376.368616] Lustre: Unmounted lustre-client [ 5399.670884] LustreError: lustre-MDT0000-mdc-ffff98eb45781000: operation mds_connect to node 192.168.204.103@tcp failed: rc = -16 [ 5404.854409] LustreError: 111218:0:(lmv_obd.c:1468:lmv_statfs()) lustre-MDT0000-mdc-ffff98eb45781000: can't stat MDS #0: rc = -16 [ 5404.976974] Lustre: Unmounted lustre-client [ 5405.001785] LustreError: 111218:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 5431.777934] Key type lgssc unregistered [ 5432.016573] LNet: 111669:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5432.032640] LNetError: 111669:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5432.054865] LNet: Removed LNI 192.168.204.3@tcp [ 5432.783508] Key type .llcrypt unregistered [ 5432.790088] Key type ._llcrypt unregistered [ 5449.711357] Key type ._llcrypt registered [ 5449.717506] Key type .llcrypt registered [ 5451.084709] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 02:54:11 (1788159251) [ 5453.302646] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5453.408200] alg: No test for adler32 (adler32-zlib) [ 5454.588834] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 5454.838834] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 5456.552323] Key type lgssc registered [ 5458.015189] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5514.292565] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5519.747911] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5541.234525] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5543.994840] Lustre: Mounted lustre-client [ 5546.024840] Lustre: Unmounted lustre-client [ 5571.161922] Key type lgssc unregistered [ 5571.554702] LNet: 115199:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5571.567784] LNetError: 115199:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5571.602909] LNet: Removed LNI 192.168.204.3@tcp [ 5572.613455] Key type .llcrypt unregistered [ 5572.621518] Key type ._llcrypt unregistered [ 5589.370180] Key type ._llcrypt registered [ 5589.372795] Key type .llcrypt registered [ 5590.940959] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 02:56:31 (1788159391) [ 5593.495705] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 5595.224248] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 02:56:36 (1788159396) [ 5597.916029] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 5599.677338] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 02:56:40 (1788159400) [ 5601.935329] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5602.018384] alg: No test for adler32 (adler32-zlib) [ 5603.093538] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 5603.362574] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 5605.120252] Key type lgssc registered [ 5606.471536] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5659.881532] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5666.281395] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5689.176958] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5692.052186] Lustre: Mounted lustre-client [ 5695.298334] Lustre: Unmounted lustre-client [ 5725.060705] Key type lgssc unregistered [ 5725.378334] LNet: 119388:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5725.387271] LNetError: 119388:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5725.407385] LNet: Removed LNI 192.168.204.3@tcp [ 5726.161364] Key type .llcrypt unregistered [ 5726.162962] Key type ._llcrypt unregistered [ 5743.311527] Key type ._llcrypt registered [ 5743.313493] Key type .llcrypt registered [ 5744.740601] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 02:59:05 (1788159545) [ 5745.755978] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5745.808682] alg: No test for adler32 (adler32-zlib) [ 5746.842731] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 5747.048285] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 5748.729776] Key type lgssc registered [ 5750.164831] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5819.648808] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5825.908883] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5844.566880] Key type lgssc unregistered [ 5844.978320] LNet: 122469:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5844.996066] LNetError: 122469:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5845.018950] LNet: Removed LNI 192.168.204.3@tcp [ 5845.808807] Key type .llcrypt unregistered [ 5845.818157] Key type ._llcrypt unregistered [ 5862.718033] Key type ._llcrypt registered [ 5862.720326] Key type .llcrypt registered [ 5864.105187] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 03:01:04 (1788159664) [ 5873.012798] Key type .llcrypt unregistered [ 5873.017448] Key type ._llcrypt unregistered [ 5881.224625] Key type ._llcrypt registered [ 5881.226937] Key type .llcrypt registered [ 5881.622841] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5881.680668] alg: No test for adler32 (adler32-zlib) [ 5882.888076] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 5883.092324] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 5884.784885] Key type lgssc registered [ 5886.138159] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5972.333610] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6006.440474] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6012.323659] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6050.470789] Key type lgssc unregistered [ 6050.763689] LNet: 126355:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6050.781273] LNetError: 126355:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6050.813571] LNet: Removed LNI 192.168.204.3@tcp [ 6051.630157] Key type .llcrypt unregistered [ 6051.632524] Key type ._llcrypt unregistered [ 6070.273419] Key type ._llcrypt registered [ 6070.280099] Key type .llcrypt registered [ 6071.774241] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 03:04:32 (1788159872) [ 6075.354526] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6075.383457] alg: No test for adler32 (adler32-zlib) [ 6076.454322] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 6076.737676] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 6078.432606] Key type lgssc registered [ 6080.694734] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6140.864727] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6148.425987] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6175.278658] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6182.490466] Lustre: Mounted lustre-client [ 6187.492739] Lustre: lustre-MDT0000-mdc-ffff98eb5172c000: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6202.848735] Lustre: 127329:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788159989/real 1788159989] req@00000000e4e35421 x1875021532237184/t0(0) o400->MGC192.168.204.103@tcp@192.168.204.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1788160005 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6202.892152] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [ 6213.100906] Lustre: Evicted from MGS (at 192.168.204.103@tcp) after server handle changed from 0x996a3ea8699b789 to 0x996a3ea8699b8c4 [ 6213.116512] Lustre: MGC192.168.204.103@tcp: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 6219.507783] Lustre: lustre-MDT0000-mdc-ffff98eb5172c000: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 6248.754992] Lustre: Unmounted lustre-client [ 6284.401521] Key type lgssc unregistered [ 6284.692024] LNet: 130372:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6284.699414] LNetError: 130372:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6284.743472] LNet: Removed LNI 192.168.204.3@tcp [ 6285.714495] Key type .llcrypt unregistered [ 6285.716389] Key type ._llcrypt unregistered [ 6303.040273] Key type ._llcrypt registered [ 6303.042622] Key type .llcrypt registered [ 6304.186439] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 03:08:25 (1788160105) [ 6306.865341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6307.008324] alg: No test for adler32 (adler32-zlib) [ 6308.136687] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 6308.376185] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 6310.072227] Key type lgssc registered [ 6311.455143] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6366.286731] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6373.020284] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6395.059576] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6397.806545] Lustre: Mounted lustre-client [ 6413.557269] Lustre: Unmounted lustre-client [ 6414.086384] Lustre: Mounted lustre-client [ 6431.451231] Lustre: Unmounted lustre-client [ 6467.442231] Key type lgssc unregistered [ 6467.727620] LNet: 134612:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6467.740956] LNetError: 134612:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6467.780810] LNet: Removed LNI 192.168.204.3@tcp [ 6468.600396] Key type .llcrypt unregistered [ 6468.605364] Key type ._llcrypt unregistered [ 6479.894890] Key type ._llcrypt registered [ 6479.897988] Key type .llcrypt registered [ 6481.142624] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 03:11:21 (1788160281) [ 6484.153485] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6484.168642] alg: No test for adler32 (adler32-zlib) [ 6485.293892] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 6485.709355] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 6487.496211] Key type lgssc registered [ 6488.818270] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6547.022898] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6554.033875] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6578.795262] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6581.575229] Lustre: Mounted lustre-client [ 6625.528027] Lustre: Unmounted lustre-client [ 6661.316858] Key type lgssc unregistered [ 6661.683803] LNet: 137996:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6661.692798] LNetError: 137996:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6661.710381] LNet: Removed LNI 192.168.204.3@tcp [ 6662.587344] Key type .llcrypt unregistered [ 6662.591473] Key type ._llcrypt unregistered [ 6679.372047] Key type ._llcrypt registered [ 6679.374256] Key type .llcrypt registered [ 6680.688317] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 03:14:41 (1788160481) [ 6683.558637] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6683.612053] alg: No test for adler32 (adler32-zlib) [ 6684.705378] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 6685.095631] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 6686.824259] Key type lgssc registered [ 6688.361537] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6743.821158] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6750.400224] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6777.646430] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6779.620677] Lustre: Mounted lustre-client [ 6782.713658] Lustre: Unmounted lustre-client [ 6783.378862] Lustre: Mounted lustre-client [ 6791.669893] Lustre: Unmounted lustre-client [ 6823.997673] Key type lgssc unregistered [ 6824.513721] LNet: 142044:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6824.526455] LNetError: 142044:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6824.549368] LNet: Removed LNI 192.168.204.3@tcp [ 6825.178960] Key type .llcrypt unregistered [ 6825.180757] Key type ._llcrypt unregistered [ 6837.060493] Key type ._llcrypt registered [ 6837.064802] Key type .llcrypt registered [ 6838.878163] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 03:17:19 (1788160639) [ 6841.645159] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6841.782627] alg: No test for adler32 (adler32-zlib) [ 6842.878063] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 6843.233146] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 6844.928208] Key type lgssc registered [ 6846.357995] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6898.762696] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6905.575365] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6929.660446] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6931.372141] Lustre: Mounted lustre-client [ 6934.159208] Lustre: Unmounted lustre-client [ 6934.639324] Lustre: Mounted lustre-client [ 6944.923290] Lustre: Unmounted lustre-client [ 6986.216242] Key type lgssc unregistered [ 6986.494753] LNet: 145667:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6986.503291] LNetError: 145667:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6986.525949] LNet: Removed LNI 192.168.204.3@tcp [ 6987.173859] Key type .llcrypt unregistered [ 6987.179227] Key type ._llcrypt unregistered [ 7003.694662] Key type ._llcrypt registered [ 7003.696240] Key type .llcrypt registered [ 7004.909387] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 03:20:05 (1788160805) [ 7008.447387] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7008.490509] alg: No test for adler32 (adler32-zlib) [ 7009.647560] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 7009.941186] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 7011.665627] Key type lgssc registered [ 7012.968969] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7066.139479] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7072.826503] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7095.731497] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7098.418219] Lustre: Mounted lustre-client [ 7125.511599] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7128.667135] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7150.105664] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7155.704807] Lustre: Unmounted lustre-client [ 7156.191238] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7156.388798] Lustre: Mounted lustre-client [ 7168.077961] Lustre: Unmounted lustre-client [ 7209.039925] Key type lgssc unregistered [ 7209.312086] LNet: 149733:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7209.321879] LNetError: 149733:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7209.340399] LNet: Removed LNI 192.168.204.3@tcp [ 7210.026625] Key type .llcrypt unregistered [ 7210.029213] Key type ._llcrypt unregistered [ 7243.139902] Key type ._llcrypt registered [ 7243.142510] Key type .llcrypt registered [ 7244.595940] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 03:24:05 (1788161045) [ 7247.330520] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7247.435909] alg: No test for adler32 (adler32-zlib) [ 7248.478793] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 7248.707945] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 7250.385047] Key type lgssc registered [ 7251.537549] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7302.627210] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7308.652497] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7333.547722] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7335.312235] Lustre: Mounted lustre-client [ 7495.767673] Lustre: Unmounted lustre-client [ 7496.346882] Lustre: Mounted lustre-client [ 7499.516645] Lustre: Unmounted lustre-client [ 7500.056769] Lustre: Mounted lustre-client [ 7502.466536] Lustre: Unmounted lustre-client [ 7532.759093] Key type lgssc unregistered [ 7533.019661] LNet: 154994:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7533.029206] LNetError: 154994:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7533.047929] LNet: Removed LNI 192.168.204.3@tcp [ 7533.586604] Key type .llcrypt unregistered [ 7533.589406] Key type ._llcrypt unregistered [ 7550.074529] Key type ._llcrypt registered [ 7550.077359] Key type .llcrypt registered [ 7551.638832] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 03:29:12 (1788161352) [ 7554.656353] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7554.685278] alg: No test for adler32 (adler32-zlib) [ 7555.772741] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 7556.021497] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 7557.762908] Key type lgssc registered [ 7558.989660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7613.085719] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7619.005463] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7642.463873] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7644.474095] Lustre: Mounted lustre-client [ 7653.906904] Lustre: Unmounted lustre-client [ 7654.437316] Lustre: Mounted lustre-client [ 7656.577771] Lustre: Unmounted lustre-client [ 7686.404347] Key type lgssc unregistered [ 7686.763101] LNet: 158700:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7686.776287] LNetError: 158700:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7686.807141] LNet: Removed LNI 192.168.204.3@tcp [ 7687.815256] Key type .llcrypt unregistered [ 7687.820431] Key type ._llcrypt unregistered [ 7706.022609] Key type ._llcrypt registered [ 7706.024740] Key type .llcrypt registered [ 7707.064887] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 03:31:48 (1788161508) [ 7708.353649] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7708.465816] alg: No test for adler32 (adler32-zlib) [ 7709.566666] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 7709.811526] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 7711.480288] Key type lgssc registered [ 7712.532813] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7737.184572] LustreError: 160397:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 7742.435503] LustreError: 160406:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 7787.680177] LNet: 1 local NIs in recovery (showing 1): 192.168.204.3@tcp [ 7861.408184] LNet: 1 local NIs in recovery (showing 1): 192.168.204.3@tcp [ 7897.056785] 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 [ 7897.089516] Lustre: Unmounted lustre-client [ 7897.144882] LustreError: 160397:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 7909.287176] Key type lgssc unregistered [ 7915.683977] LNet: 160811:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7915.695689] LNetError: 160811:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7992.550750] LNet: Removed LNI 192.168.204.3@tcp [ 7993.365244] Key type .llcrypt unregistered [ 7993.368527] Key type ._llcrypt unregistered [ 8034.627145] Key type ._llcrypt registered [ 8034.636292] Key type .llcrypt registered [ 8034.985519] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8035.013911] alg: No test for adler32 (adler32-zlib) [ 8036.193693] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 8036.485243] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 8038.200543] Key type lgssc registered [ 8039.748545] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8092.092903] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7844 sec ========== 03:38:12 (1788161892) [ 8094.165267] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 03:38:14 (1788161894) === [ 8097.873172] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 03:38:18 (1788161898) === [ 8133.048524] Key type lgssc unregistered [ 8133.381482] LNet: 165069:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8133.390329] LNetError: 165069:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8133.409463] LNet: Removed LNI 192.168.204.3@tcp [ 8134.176134] Key type .llcrypt unregistered [ 8134.177895] Key type ._llcrypt unregistered