[ 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 487421411 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001008] APIC: Switch to symmetric I/O mode setup [ 0.003260] x2apic enabled [ 0.004007] Switched APIC routing to physical x2apic. [ 0.006009] kvm-guest: setup PV IPIs [ 0.008877] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009044] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010020] pid_max: default: 32768 minimum: 301 [ 0.011176] LSM: Security Framework initializing [ 0.012078] Yama: becoming mindful. [ 0.013063] SELinux: Initializing. [ 0.014121] *** VALIDATE selinux *** [ 0.023443] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028904] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030194] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031148] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032151] *** VALIDATE tmpfs *** [ 0.033584] *** VALIDATE proc *** [ 0.034332] *** VALIDATE cgroup *** [ 0.035017] *** VALIDATE cgroup2 *** [ 0.037251] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039185] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.041007] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.042039] Spectre V2 : User space: Vulnerable [ 0.043014] Speculative Store Bypass: Vulnerable [ 0.047038] debug: unmapping init [mem 0xffffffffa6659000-0xffffffffa6660fff] [ 0.049305] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.050892] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.051034] ... version: 2 [ 0.052031] ... bit width: 48 [ 0.053016] ... generic registers: 4 [ 0.054017] ... value mask: 0000ffffffffffff [ 0.055023] ... max period: 00007fffffffffff [ 0.056023] ... fixed-purpose events: 3 [ 0.057019] ... event mask: 000000070000000f [ 0.059307] rcu: Hierarchical SRCU implementation. [ 0.061841] smp: Bringing up secondary CPUs ... [ 0.062662] x86: Booting SMP configuration: [ 0.063030] .... node #0, CPUs: #1 #2 #3 [ 0.067415] smp: Brought up 1 node, 4 CPUs [ 0.069016] smpboot: Max logical packages: 1 [ 0.070013] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.143186] node 0 deferred pages initialised in 71ms [ 0.147117] devtmpfs: initialized [ 0.148318] x86/mm: Memory block size: 128MB [ 0.152126] gcov: version magic: 0x41383552 [ 0.155345] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.160156] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.163386] pinctrl core: initialized pinctrl subsystem [ 0.166267] [ 0.167009] ************************************************************* [ 0.170020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.173015] ** ** [ 0.176015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.179032] ** ** [ 0.182019] ** This means that this kernel is built to expose internal ** [ 0.185015] ** IOMMU data structures, which may compromise security on ** [ 0.188015] ** your system. ** [ 0.191016] ** ** [ 0.194018] ** If you see this message and you are not debugging the ** [ 0.197016] ** kernel, report this immediately to your vendor! ** [ 0.200063] ** ** [ 0.202012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.204076] ************************************************************* [ 0.208021] NET: Registered protocol family 16 [ 0.210538] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.215086] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.219143] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.225037] cpuidle: using governor menu [ 0.227355] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.231938] PCI: Using configuration type 1 for base access [ 0.234148] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.247077] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.252050] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.256079] cryptd: max_cpu_qlen set to 1000 [ 0.259155] ACPI: Added _OSI(Module Device) [ 0.261020] ACPI: Added _OSI(Processor Device) [ 0.263035] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.264015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.269133] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.275585] ACPI: Interpreter enabled [ 0.277083] ACPI: PM: (supports S0 S3 S4 S5) [ 0.279032] ACPI: Using IOAPIC for interrupt routing [ 0.281161] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.286486] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.297067] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.300060] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.303026] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.307103] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.312970] acpiphp: Slot [2] registered [ 0.314156] acpiphp: Slot [5] registered [ 0.316134] acpiphp: Slot [6] registered [ 0.318151] acpiphp: Slot [3] registered [ 0.319125] acpiphp: Slot [4] registered [ 0.321170] acpiphp: Slot [7] registered [ 0.322123] acpiphp: Slot [8] registered [ 0.324137] acpiphp: Slot [9] registered [ 0.326130] acpiphp: Slot [10] registered [ 0.327087] acpiphp: Slot [11] registered [ 0.328088] acpiphp: Slot [12] registered [ 0.330173] acpiphp: Slot [13] registered [ 0.332139] acpiphp: Slot [14] registered [ 0.333170] acpiphp: Slot [15] registered [ 0.335125] acpiphp: Slot [16] registered [ 0.337138] acpiphp: Slot [17] registered [ 0.339166] acpiphp: Slot [18] registered [ 0.341137] acpiphp: Slot [19] registered [ 0.342187] acpiphp: Slot [20] registered [ 0.344137] acpiphp: Slot [21] registered [ 0.346145] acpiphp: Slot [22] registered [ 0.347126] acpiphp: Slot [23] registered [ 0.349130] acpiphp: Slot [24] registered [ 0.351088] acpiphp: Slot [25] registered [ 0.352123] acpiphp: Slot [26] registered [ 0.354125] acpiphp: Slot [27] registered [ 0.356150] acpiphp: Slot [28] registered [ 0.358145] acpiphp: Slot [29] registered [ 0.360168] acpiphp: Slot [30] registered [ 0.361132] acpiphp: Slot [31] registered [ 0.363080] PCI host bridge to bus 0000:00 [ 0.365026] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.367028] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.370031] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.372028] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.375026] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.378033] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.380208] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.383138] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.388605] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.396019] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.400150] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.404024] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.407032] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.409021] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.411663] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.415894] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.418051] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.420990] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.425068] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.436031] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.441018] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.446838] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.451027] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.461019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.487018] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.496322] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.504020] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.512828] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.526974] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.537633] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.540495] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.543439] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.546431] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.549269] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.554180] iommu: Default domain type: Passthrough [ 0.557742] SCSI subsystem initialized [ 0.559210] ACPI: bus type USB registered [ 0.561143] usbcore: registered new interface driver usbfs [ 0.563128] usbcore: registered new interface driver hub [ 0.566120] usbcore: registered new device driver usb [ 0.568183] pps_core: LinuxPPS API ver. 1 registered [ 0.570019] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.573159] PTP clock support registered [ 0.576164] EDAC MC: Ver: 3.0.0 [ 0.578165] PCI: Using ACPI for IRQ routing [ 0.580642] NetLabel: Initializing [ 0.582013] NetLabel: domain hash size = 128 [ 0.583009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.585130] NetLabel: unlabeled traffic allowed by default [ 0.587142] vgaarb: loaded [ 0.589385] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.590014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.594424] clocksource: Switched to clocksource kvm-clock [ 0.705318] VFS: Disk quotas dquot_6.6.0 [ 0.707053] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.709973] *** VALIDATE ramfs *** [ 0.711521] *** VALIDATE hugetlbfs *** [ 0.713566] pnp: PnP ACPI init [ 0.716659] pnp: PnP ACPI: found 6 devices [ 0.742086] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.745396] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.748085] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.750827] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.753581] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.756410] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.759534] NET: Registered protocol family 2 [ 0.762233] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.767149] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.771961] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.778461] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.782867] TCP: Hash tables configured (established 65536 bind 65536) [ 0.786224] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.789732] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.793084] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.796258] NET: Registered protocol family 1 [ 0.799525] RPC: Registered named UNIX socket transport module. [ 0.802366] RPC: Registered udp transport module. [ 0.803995] RPC: Registered tcp transport module. [ 0.805798] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.808440] NET: Registered protocol family 44 [ 0.810427] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.812464] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.815863] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.818297] PCI: CLS 0 bytes, default 64 [ 0.820167] Unpacking initramfs... [ 2.249647] debug: unmapping init [mem 0xffff8b70bcc64000-0xffff8b70bffcffff] [ 2.254332] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.256945] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.260521] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.808459] Initialise system trusted keyrings [ 2.811810] Key type blacklist registered [ 2.815204] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.826213] zbud: loaded [ 2.831939] *** VALIDATE nfs *** [ 2.833365] *** VALIDATE nfs4 *** [ 2.835180] pstore: using deflate compression [ 2.839474] Platform Keyring initialized [ 2.972967] NET: Registered protocol family 38 [ 2.974944] Key type asymmetric registered [ 2.976865] Asymmetric key parser 'x509' registered [ 2.978607] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.981925] io scheduler mq-deadline registered [ 2.983848] io scheduler kyber registered [ 2.985685] io scheduler bfq registered [ 2.987698] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.990400] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.994514] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.997307] ACPI: Power Button [PWRF] [ 3.002346] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.008583] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.026383] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.059526] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.089785] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.097031] Non-volatile memory driver v1.3 [ 3.098636] Linux agpgart interface v0.103 [ 3.128230] virtio_blk virtio1: [vda] 146152 512-byte logical blocks (74.8 MB/71.4 MiB) [ 3.131510] vda: detected capacity change from 0 to 74829824 [ 3.150990] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.154054] vdb: detected capacity change from 0 to 1073741824 [ 3.165138] libphy: Fixed MDIO Bus: probed [ 3.181435] usbcore: registered new interface driver usbserial_generic [ 3.185149] usbserial: USB Serial support registered for generic [ 3.188865] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.194670] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.196462] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.201114] mousedev: PS/2 mouse device common for all mice [ 3.205534] rtc_cmos 00:05: RTC can wake from S4 [ 3.207670] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.212655] rtc_cmos 00:05: registered as rtc0 [ 3.214882] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.216733] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.218075] intel_pstate: CPU model not supported [ 3.224903] hid: raw HID events driver (C) Jiri Kosina [ 3.227211] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.227568] usbcore: registered new interface driver usbhid [ 3.233357] usbhid: USB HID core driver [ 3.235349] drop_monitor: Initializing network drop monitor service [ 3.237546] Initializing XFRM netlink socket [ 3.240307] NET: Registered protocol family 10 [ 3.243550] Segment Routing with IPv6 [ 3.245137] NET: Registered protocol family 17 [ 3.247097] mpls_gso: MPLS GSO support [ 3.253873] RAS: Correctable Errors collector initialized. [ 3.256563] AVX version of gcm_enc/dec engaged. [ 3.257867] AES CTR mode by8 optimization enabled [ 3.338871] sched_clock: Marking stable (3338833117, 0)->(4269038816, -930205699) [ 3.342833] registered taskstats version 1 [ 3.345302] Loading compiled-in X.509 certificates [ 3.347184] zswap: loaded using pool lzo/zbud [ 3.370935] Key type big_key registered [ 3.383698] Key type encrypted registered [ 3.385584] ima: No TPM chip found, activating TPM-bypass! [ 3.387807] ima: Allocated hash algorithm: sha1 [ 3.389332] ima: No architecture policies found [ 3.390892] evm: Initialising EVM extended attributes: [ 3.392865] evm: security.selinux [ 3.394200] evm: security.ima [ 3.395209] evm: security.capability [ 3.396598] evm: HMAC attrs: 0x1 [ 3.399364] rtc_cmos 00:05: setting system clock to 2026-08-26 00:56:18 UTC (1787705778) [ 3.408952] debug: unmapping init [mem 0xffffffffa7603000-0xffffffffa77fffff] [ 3.411951] debug: unmapping init [mem 0xffffffffa6382000-0xffffffffa6658fff] [ 3.420150] Write protecting the kernel read-only data: 28672k [ 3.424256] debug: unmapping init [mem 0xffffffffa4a03000-0xffffffffa4bfffff] [ 3.427771] debug: unmapping init [mem 0xffffffffa5314000-0xffffffffa53fffff] [ 3.458977] 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.465710] systemd[1]: Detected virtualization kvm. [ 3.467410] systemd[1]: Detected architecture x86-64. [ 3.469110] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.498672] systemd[1]: No hostname configured. [ 3.500277] systemd[1]: Set hostname to . [ 3.502183] random: systemd: uninitialized urandom read (16 bytes read) [ 3.504181] systemd[1]: Initializing machine ID from random generator. [ 3.562536] random: ln: uninitialized urandom read (6 bytes read) [ 3.653612] random: systemd: uninitialized urandom read (16 bytes read) [ 3.656183] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.661974] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.667638] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Paths. [ OK ] Reached target Slices. [ OK ] Reached target Local Encrypted Volumes. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Timers. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.338727] device-mapper: uevent: version 1.0.3 [ 4.342178] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.079680] virtio_net virtio0 ens2: renamed from eth0 [ 5.144772] scsi host0: ata_piix [ 5.154320] scsi host1: ata_piix [ 5.155873] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.158566] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.752447] dracut-initqueue[580]: RTNETLINK answers: File exists [ 9.901598] random: crng init done [ 9.903319] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.254778] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.349906] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.590318] SELinux: Disabled at runtime. [ 11.643490] 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.651237] systemd[1]: Detected virtualization kvm. [ 11.652865] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.153562] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.157444] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.162110] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.168329] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.172061] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.180835] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.185963] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-getty.slice. Mounting POSIX Message Queue File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. Mounting Kernel Debug File System... [ OK ] Listening on udev Control Socket. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Huge Pages File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Initrd Root File System. Starting udev Coldplug all Devices... Starting Apply Kernel Variables... Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Journal Service. [ 12.368486] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.619216] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.947976] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.013883] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.057644] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.070230] EDAC sbridge: Ver: 1.1.2 [ 14.120326] Key type dns_resolver registered [ 14.432375] NFS: Registering the id_resolver key type [ 14.434385] Key type id_resolver registered [ 14.436021] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting 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. [ OK ] Started Authorization Manager. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg627-client login: [ 49.483814] libcfs: loading out-of-tree module taints kernel. [ 49.697610] Key type ._llcrypt registered [ 49.704686] Key type .llcrypt registered [ 50.828251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 50.872166] alg: No test for adler32 (adler32-zlib) [ 52.680813] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 53.484576] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 55.264211] Key type lgssc registered [ 57.500913] Lustre: Echo OBD driver; http://www.lustre.org/ [ 149.105274] hrtimer: interrupt took 3439877 ns [ 224.789357] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 230.228363] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 247.529756] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing check_logdir /tmp/testlogs/ [ 250.336283] Lustre: lustre-OST0000-osc-ffff8b7111601800: disconnect after 23s idle [ 252.438289] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing yml_node [ 257.825903] Lustre: DEBUG MARKER: Client: 2.17.57.80 [ 260.863104] Lustre: DEBUG MARKER: MDS: 2.17.57.80 [ 263.576382] Lustre: DEBUG MARKER: OSS: 2.17.57.80 [ 265.155041] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Aug 25 21:00:38 EDT 2026 [ 282.757409] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 283.956892] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 286.472892] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 296.793695] Lustre: Unmounted lustre-client [ 423.033900] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 428.945928] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 454.696169] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 492.195323] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 21:04:25 (1787706265) [ 524.669368] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 529.669351] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 549.950495] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 550.670546] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 555.759528] Lustre: Unmounted lustre-client [ 585.803541] Key type lgssc unregistered [ 586.047603] LNet: 14712:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 586.060648] LNetError: 14712:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 586.083515] LNet: Removed LNI 192.168.206.27@tcp [ 586.837271] Key type .llcrypt unregistered [ 586.839133] Key type ._llcrypt unregistered [ 601.817685] Key type ._llcrypt registered [ 601.825200] Key type .llcrypt registered [ 602.981614] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 21:06:16 (1787706376) [ 605.223370] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 605.251579] alg: No test for adler32 (adler32-zlib) [ 606.286660] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 606.494351] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 608.169865] Key type lgssc registered [ 609.186913] Lustre: Echo OBD driver; http://www.lustre.org/ [ 658.179826] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 663.514360] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 685.079930] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 695.496687] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 699.079095] Lustre: Unmounted lustre-client [ 728.349250] Key type lgssc unregistered [ 728.647933] LNet: 18392:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 728.668369] LNetError: 18392:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 728.706550] LNet: Removed LNI 192.168.206.27@tcp [ 729.284534] Key type .llcrypt unregistered [ 729.290122] Key type ._llcrypt unregistered [ 745.413318] Key type ._llcrypt registered [ 745.419297] Key type .llcrypt registered [ 747.107564] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 21:08:40 (1787706520) [ 749.455427] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 749.503083] alg: No test for adler32 (adler32-zlib) [ 750.635991] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 750.998702] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 752.784172] Key type lgssc registered [ 753.944680] Lustre: Echo OBD driver; http://www.lustre.org/ [ 809.205793] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 815.434570] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 846.697715] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 852.571591] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 854.778807] Lustre: Unmounted lustre-client [ 882.880684] Key type lgssc unregistered [ 883.135535] LNet: 22048:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 883.150286] LNetError: 22048:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 883.168959] LNet: Removed LNI 192.168.206.27@tcp [ 883.858900] Key type .llcrypt unregistered [ 883.862846] Key type ._llcrypt unregistered [ 899.993504] Key type ._llcrypt registered [ 899.995374] Key type .llcrypt registered [ 901.304944] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 21:11:14 (1787706674) [ 903.808924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 903.947879] alg: No test for adler32 (adler32-zlib) [ 904.992911] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 905.232722] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 906.936301] Key type lgssc registered [ 908.227995] Lustre: Echo OBD driver; http://www.lustre.org/ [ 961.824981] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 967.335266] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 987.843848] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 990.663752] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 998.409267] Lustre: Unmounted lustre-client [ 1026.512114] Key type lgssc unregistered [ 1026.810249] LNet: 25668:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1026.829802] LNetError: 25668:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1026.868854] LNet: Removed LNI 192.168.206.27@tcp [ 1027.598967] Key type .llcrypt unregistered [ 1027.603213] Key type ._llcrypt unregistered [ 1045.667508] Key type ._llcrypt registered [ 1045.670147] Key type .llcrypt registered [ 1046.947927] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 21:13:40 (1787706820) [ 1048.597556] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1048.724586] alg: No test for adler32 (adler32-zlib) [ 1049.808906] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 1050.025197] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 1051.672316] Key type lgssc registered [ 1052.817505] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1101.370905] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1108.236437] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1131.425399] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1134.314342] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 1139.721544] Lustre: lustre-OST0000-osc-ffff8b7112cc8000: Connection to lustre-OST0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1150.509149] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1160.998205] Lustre: Unmounted lustre-client [ 1188.284276] Key type lgssc unregistered [ 1188.501955] LNet: 29291:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1188.509236] LNetError: 29291:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1188.528569] LNet: Removed LNI 192.168.206.27@tcp [ 1189.059044] Key type .llcrypt unregistered [ 1189.061373] Key type ._llcrypt unregistered [ 1205.073391] Key type ._llcrypt registered [ 1205.079802] Key type .llcrypt registered [ 1206.692411] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 21:16:19 (1787706979) [ 1209.210783] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1209.228226] alg: No test for adler32 (adler32-zlib) [ 1210.466299] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 1210.722269] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 1212.408195] Key type lgssc registered [ 1213.527946] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1264.087234] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1271.120739] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1292.444772] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1294.143089] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 1304.566629] Lustre: lustre-MDT0000-mdc-ffff8b7112064000: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1314.798143] Lustre: lustre-MDT0001-mdc-ffff8b7112064000: Connection to lustre-MDT0001 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1319.892821] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1325.024231] Lustre: 30252:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787707084/real 1787707084] req@0000000008b23d46 x1874546639509632/t0(0) o400->MGC192.168.206.127@tcp@192.168.206.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1787707100 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1325.063484] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 1330.244181] Lustre: Unmounted lustre-client [ 1343.023347] Key type lgssc unregistered [ 1343.292919] LNet: 32952:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1343.301534] LNetError: 32952:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1343.320842] LNet: Removed LNI 192.168.206.27@tcp [ 1343.908863] Key type .llcrypt unregistered [ 1343.913173] Key type ._llcrypt unregistered [ 1361.022175] Key type ._llcrypt registered [ 1361.026322] Key type .llcrypt registered [ 1362.224349] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 21:18:55 (1787707135) [ 1364.767672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1364.793312] alg: No test for adler32 (adler32-zlib) [ 1365.897269] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 1366.179844] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 1367.816165] Key type lgssc registered [ 1368.818697] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1453.326713] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1458.976736] LustreError: 35156:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.206.127@tcp: failed processing log, type 1: rc = -110 [ 1465.826487] LustreError: 35165:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1617.888609] LustreError: MGC192.168.206.127@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 [ 1617.922408] Lustre: Unmounted lustre-client [ 1617.934754] LustreError: 35156:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1630.994956] Key type lgssc unregistered [ 1631.241383] LNet: 35594:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1631.254308] LNetError: 35594:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1631.273244] LNet: Removed LNI 192.168.206.27@tcp [ 1632.040802] Key type .llcrypt unregistered [ 1632.044364] Key type ._llcrypt unregistered [ 1645.111712] Key type ._llcrypt registered [ 1645.115336] Key type .llcrypt registered [ 1646.426660] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 21:23:40 (1787707420) [ 1648.670612] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1648.695486] alg: No test for adler32 (adler32-zlib) [ 1649.775203] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 1650.007824] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 1651.664198] Key type lgssc registered [ 1652.691448] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1699.117539] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1704.070394] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1722.974404] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1723.330272] LustreError: 38688:0:(llite_lib.c:1555:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1723.344939] Lustre: Unmounted wrong.lustre-client [ 1723.353561] LustreError: 38688:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1748.434904] Key type lgssc unregistered [ 1748.611252] LNet: 39106:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1748.624359] LNetError: 39106:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1748.646975] LNet: Removed LNI 192.168.206.27@tcp [ 1749.186305] Key type .llcrypt unregistered [ 1749.189854] Key type ._llcrypt unregistered [ 1762.057059] Key type ._llcrypt registered [ 1762.059519] Key type .llcrypt registered [ 1763.026935] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 21:25:37 (1787707537) [ 1764.860252] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1764.872442] alg: No test for adler32 (adler32-zlib) [ 1765.828995] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 1765.998666] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 1767.657126] Key type lgssc registered [ 1768.570313] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1844.975702] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1871.335074] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1875.526889] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1895.855095] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 1897.866349] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1908.239222] Lustre: Unmounted lustre-client [ 1931.513411] Key type lgssc unregistered [ 1931.748992] LNet: 42679:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1931.753830] LNetError: 42679:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1931.767379] LNet: Removed LNI 192.168.206.27@tcp [ 1932.356560] Key type .llcrypt unregistered [ 1932.358987] Key type ._llcrypt unregistered [ 1944.864685] Key type ._llcrypt registered [ 1944.867418] Key type .llcrypt registered [ 1945.938176] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 21:28:39 (1787707719) [ 1948.079588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1948.096192] alg: No test for adler32 (adler32-zlib) [ 1949.175861] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 1949.404870] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 1951.056200] Key type lgssc registered [ 1952.243406] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1994.196880] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1999.012485] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2018.230617] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2019.617610] LustreError: 43635:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2023.712920] LustreError: 45758:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.206.127@tcp: failed processing log, type 1: rc = -110 [ 2029.624675] LustreError: 43635:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2030.913465] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2032.777280] Lustre: Unmounted lustre-client [ 2059.004837] Key type lgssc unregistered [ 2059.241062] LNet: 46217:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2059.252224] LNetError: 46217:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2059.273487] LNet: Removed LNI 192.168.206.27@tcp [ 2060.034571] Key type .llcrypt unregistered [ 2060.041842] Key type ._llcrypt unregistered [ 2073.918610] Key type ._llcrypt registered [ 2073.922217] Key type .llcrypt registered [ 2074.990956] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 21:30:48 (1787707848) [ 2076.579852] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2078.644951] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 21:30:52 (1787707852) [ 2079.932684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2079.968693] alg: No test for adler32 (adler32-zlib) [ 2081.020459] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 2081.232736] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2082.938841] Key type lgssc registered [ 2084.154509] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2107.773408] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 21:31:21 (1787707881) [ 2137.437974] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2142.291947] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2157.945733] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2164.790090] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2169.839623] Lustre: lustre-MDT0000-mdc-ffff8b7112060000: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2185.120426] Lustre: 47438:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787707944/real 1787707944] req@00000000d04be540 x1874547552817024/t0(0) o400->MGC192.168.206.127@tcp@192.168.206.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1787707960 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2185.152156] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 2185.194344] Lustre: Evicted from MGS (at 192.168.206.127@tcp) after server handle changed from 0xc666fabb73711c57 to 0xc666fabb73711f28 [ 2185.209194] Lustre: MGC192.168.206.127@tcp: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 2189.232741] Lustre: lustre-MDT0000-mdc-ffff8b7112060000: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 2194.067990] Lustre: Unmounted lustre-client [ 2219.076906] Key type lgssc unregistered [ 2219.284943] LNet: 51077:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2219.292272] LNetError: 51077:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2219.314518] LNet: Removed LNI 192.168.206.27@tcp [ 2219.912124] Key type .llcrypt unregistered [ 2219.914506] Key type ._llcrypt unregistered [ 2235.131637] Key type ._llcrypt registered [ 2235.134269] Key type .llcrypt registered [ 2236.523637] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 21:33:30 (1787708010) [ 2239.369280] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2239.385689] alg: No test for adler32 (adler32-zlib) [ 2240.475337] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 2240.825572] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2242.553448] Key type lgssc registered [ 2243.532438] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2288.410919] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2294.039161] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2312.301856] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2314.939168] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2320.356569] Lustre: lustre-MDT0000-mdc-ffff8b71088bd000: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2325.478453] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 2325.498688] Lustre: Evicted from MGS (at 192.168.206.127@tcp) after server handle changed from 0xc1b0d86a3af20fd9 to 0xc1b0d86a3af210b9 [ 2325.517232] Lustre: MGC192.168.206.127@tcp: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 2335.727903] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 2335.744301] Lustre: 54160:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.206.127@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 2335.764203] Lustre: Evicted from MGS (at 192.168.206.127@tcp) after server handle changed from 0xc1b0d86a3af210b9 to 0xc1b0d86a3af21272 [ 2335.780282] Lustre: MGC192.168.206.127@tcp: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 2340.914789] Lustre: Unmounted lustre-client [ 2368.682263] Key type lgssc unregistered [ 2368.903274] LNet: 55038:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2368.915989] LNetError: 55038:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2368.941358] LNet: Removed LNI 192.168.206.27@tcp [ 2369.443536] Key type .llcrypt unregistered [ 2369.446297] Key type ._llcrypt unregistered [ 2381.772464] Key type ._llcrypt registered [ 2381.774121] Key type .llcrypt registered [ 2383.131377] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 21:35:56 (1787708156) [ 2383.619356] Key type .llcrypt unregistered [ 2383.621588] Key type ._llcrypt unregistered [ 2389.239603] Key type ._llcrypt registered [ 2389.245852] Key type .llcrypt registered [ 2389.296383] Key type .llcrypt unregistered [ 2389.298611] Key type ._llcrypt unregistered [ 2394.003336] Key type ._llcrypt registered [ 2394.005814] Key type .llcrypt registered [ 2395.194579] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 21:36:08 (1787708168) [ 2397.346661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2397.355599] alg: No test for adler32 (adler32-zlib) [ 2398.486726] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 2398.752301] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2400.408137] Key type lgssc registered [ 2401.475093] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2443.658773] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2448.453496] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2469.047704] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2470.742220] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2472.984045] Lustre: Unmounted lustre-client [ 2473.433041] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2475.264812] Lustre: Unmounted lustre-client [ 2501.990897] Key type lgssc unregistered [ 2502.297325] LNet: 59458:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2502.304793] LNetError: 59458:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2502.317101] LNet: Removed LNI 192.168.206.27@tcp [ 2502.877687] Key type .llcrypt unregistered [ 2502.882323] Key type ._llcrypt unregistered [ 2515.504989] Key type ._llcrypt registered [ 2515.514932] Key type .llcrypt registered [ 2516.656568] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 21:38:10 (1787708290) [ 2518.603770] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2518.656052] alg: No test for adler32 (adler32-zlib) [ 2519.683372] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 2519.871198] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2521.520270] Key type lgssc registered [ 2522.469378] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2565.923831] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2570.838605] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2588.787579] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2590.288345] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2592.348915] Lustre: Unmounted lustre-client [ 2617.524174] Key type lgssc unregistered [ 2617.742077] LNet: 63025:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2617.753685] LNetError: 63025:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2617.773885] LNet: Removed LNI 192.168.206.27@tcp [ 2618.332683] Key type .llcrypt unregistered [ 2618.335761] Key type ._llcrypt unregistered [ 2630.356744] Key type ._llcrypt registered [ 2630.358340] Key type .llcrypt registered [ 2631.496616] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 21:40:05 (1787708405) [ 2633.117290] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2633.133534] alg: No test for adler32 (adler32-zlib) [ 2634.177934] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 2634.386498] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2636.048164] Key type lgssc registered [ 2636.888585] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2674.317664] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2678.585080] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2693.406288] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2705.472065] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2707.508610] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2709.747881] Lustre: Unmounted lustre-client [ 2711.023200] Lustre: Unmounted lustre-client [ 2733.999610] Key type lgssc unregistered [ 2734.329479] LNet: 66679:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2734.336502] LNetError: 66679:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2734.359136] LNet: Removed LNI 192.168.206.27@tcp [ 2735.301783] Key type .llcrypt unregistered [ 2735.305375] Key type ._llcrypt unregistered [ 2748.544479] Key type ._llcrypt registered [ 2748.547722] Key type .llcrypt registered [ 2749.796684] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 21:42:03 (1787708523) [ 2752.107244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2752.122192] alg: No test for adler32 (adler32-zlib) [ 2753.183752] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 2753.410047] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2755.064229] Key type lgssc registered [ 2756.105448] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2830.825207] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2843.772237] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 21:43:37 (1787708617) [ 2871.145178] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2878.016614] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2896.945151] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2899.518099] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 2905.032250] Lustre: Unmounted lustre-client [ 2927.938838] Key type lgssc unregistered [ 2928.131590] LNet: 71446:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2928.142337] LNetError: 71446:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2928.162398] LNet: Removed LNI 192.168.206.27@tcp [ 2928.713581] Key type .llcrypt unregistered [ 2928.717182] Key type ._llcrypt unregistered [ 2941.376869] Key type ._llcrypt registered [ 2941.378938] Key type .llcrypt registered [ 2942.418504] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 21:45:16 (1787708716) [ 2955.785266] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 21:45:29 (1787708729) [ 2958.014156] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2958.027760] alg: No test for adler32 (adler32-zlib) [ 2959.107334] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 2959.346956] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 2961.034614] Key type lgssc registered [ 2962.096451] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3007.310706] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3014.280803] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3033.517163] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3036.470874] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 3043.823813] Lustre: Unmounted lustre-client [ 3069.797383] Key type lgssc unregistered [ 3070.104644] LNet: 75736:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3070.112665] LNetError: 75736:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3070.125966] LNet: Removed LNI 192.168.206.27@tcp [ 3070.857884] Key type .llcrypt unregistered [ 3070.859817] Key type ._llcrypt unregistered [ 3085.062549] Key type ._llcrypt registered [ 3085.068211] Key type .llcrypt registered [ 3085.607982] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3085.623139] alg: No test for adler32 (adler32-zlib) [ 3086.676715] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 3086.894974] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 3088.529305] Key type lgssc registered [ 3089.765796] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3169.216105] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3275.041215] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3280.508828] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3301.893295] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3345.933441] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 21:51:59 (1787709119) [ 3349.349632] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3440.528587] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3446.965552] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3471.234846] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3533.304332] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3537.798826] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3555.580659] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3557.244596] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 3562.620885] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 3564.868231] Lustre: Unmounted lustre-client [ 3591.116381] Key type lgssc unregistered [ 3591.365699] LNet: 87423:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3591.374951] LNetError: 87423:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3591.394959] LNet: Removed LNI 192.168.206.27@tcp [ 3591.954741] Key type .llcrypt unregistered [ 3591.959780] Key type ._llcrypt unregistered [ 3615.923127] Key type ._llcrypt registered [ 3615.927440] Key type .llcrypt registered [ 3616.353512] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3616.370506] alg: No test for adler32 (adler32-zlib) [ 3617.526843] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 3617.811208] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 3619.521277] Key type lgssc registered [ 3620.433361] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3699.567142] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3705.016908] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3727.533604] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3768.515960] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 21:59:02 (1787709542) [ 3801.651518] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3807.686885] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3829.305828] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 22:00:02 (1787709602) [ 3897.809929] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3907.570637] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 22:01:21 (1787709681) [ 3939.942617] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3945.391828] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3964.866454] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3966.505642] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 3967.183496] Lustre: Remounted lustre-client read-only [ 3969.403399] Lustre: Unmounted lustre-client [ 4003.748977] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 22:02:57 (1787709777) [ 4033.642498] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4039.641935] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4059.472140] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4097.903494] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 22:04:32 (1787709872) [ 4163.784071] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4198.685947] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4206.420986] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4252.908590] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 22:07:05 (1787710025) [ 4319.941492] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4351.814868] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4358.330661] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4382.218722] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4445.734520] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 22:10:19 (1787710219) [ 4447.332857] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 4448.984739] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 4450.744718] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 22:10:24 (1787710224) [ 4480.959147] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4485.967721] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4506.247872] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4535.285866] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 4537.110890] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4547.431910] Lustre: Unmounted lustre-client [ 4568.560674] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4569.293719] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 4587.140396] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8b7105ebe800.ost_server_uuid 50 [ 4589.103844] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8b7105ebe800.ost_server_uuid in FULL state after 0 sec [ 4592.146298] Lustre: Unmounted lustre-client [ 4625.792686] Key type lgssc unregistered [ 4626.067756] LNet: 107789:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4626.072128] LNetError: 107789:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4626.095324] LNet: Removed LNI 192.168.206.27@tcp [ 4627.180131] Key type .llcrypt unregistered [ 4627.188906] Key type ._llcrypt unregistered [ 4643.323791] Key type ._llcrypt registered [ 4643.330127] Key type .llcrypt registered [ 4644.802730] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 22:13:38 (1787710418) [ 4647.088944] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4647.109652] alg: No test for adler32 (adler32-zlib) [ 4648.199952] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 4648.438360] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 4650.129631] Key type lgssc registered [ 4651.568376] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4704.442918] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4710.607502] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4734.167279] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4735.996912] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 4746.233760] Lustre: lustre-MDT0000-mdc-ffff8b7105ebf800: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4746.265362] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4756.552427] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 4756.627528] Lustre: Unmounted lustre-client [ 4779.955680] LustreError: lustre-MDT0000-mdc-ffff8b7108034000: operation mds_connect to node 192.168.206.127@tcp failed: rc = -16 [ 4785.096853] LustreError: 111175:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8b7108034000: can't stat MDS #0: rc = -16 [ 4785.193156] Lustre: Unmounted lustre-client [ 4785.213958] LustreError: 111175:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 4809.216052] Key type lgssc unregistered [ 4809.524493] LNet: 111631:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4809.536523] LNetError: 111631:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4809.560239] LNet: Removed LNI 192.168.206.27@tcp [ 4810.150843] Key type .llcrypt unregistered [ 4810.155201] Key type ._llcrypt unregistered [ 4826.759254] Key type ._llcrypt registered [ 4826.763174] Key type .llcrypt registered [ 4828.175469] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 22:16:41 (1787710601) [ 4830.777887] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4830.810591] alg: No test for adler32 (adler32-zlib) [ 4831.953403] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 4832.173137] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 4833.841922] Key type lgssc registered [ 4835.334932] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4885.819036] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4891.562311] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4912.881243] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4915.737614] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 4917.828239] Lustre: Unmounted lustre-client [ 4952.519090] Key type lgssc unregistered [ 4952.852338] LNet: 115165:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4952.865811] LNetError: 115165:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4952.900634] LNet: Removed LNI 192.168.206.27@tcp [ 4953.944327] Key type .llcrypt unregistered [ 4953.949054] Key type ._llcrypt unregistered [ 4969.317705] Key type ._llcrypt registered [ 4969.319785] Key type .llcrypt registered [ 4970.924846] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 22:19:04 (1787710744) [ 4973.673396] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 4975.369619] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 22:19:09 (1787710749) [ 4977.700386] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 4979.397445] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 22:19:13 (1787710753) [ 4981.355947] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4981.375366] alg: No test for adler32 (adler32-zlib) [ 4982.385859] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 4982.564154] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 4984.272212] Key type lgssc registered [ 4985.574628] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5034.219344] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5038.999923] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5057.438219] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5060.111164] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 5062.099215] Lustre: Unmounted lustre-client [ 5088.244567] Key type lgssc unregistered [ 5088.508223] LNet: 119355:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5088.523446] LNetError: 119355:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5088.540739] LNet: Removed LNI 192.168.206.27@tcp [ 5089.114375] Key type .llcrypt unregistered [ 5089.116126] Key type ._llcrypt unregistered [ 5104.192899] Key type ._llcrypt registered [ 5104.200134] Key type .llcrypt registered [ 5105.399988] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 22:21:19 (1787710879) [ 5106.648431] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5106.663840] alg: No test for adler32 (adler32-zlib) [ 5107.633842] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 5107.905910] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 5109.640189] Key type lgssc registered [ 5110.750398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5171.793881] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5178.107695] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5196.347425] Key type lgssc unregistered [ 5196.684285] LNet: 122439:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5196.696233] LNetError: 122439:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5196.722385] LNet: Removed LNI 192.168.206.27@tcp [ 5197.417756] Key type .llcrypt unregistered [ 5197.419456] Key type ._llcrypt unregistered [ 5211.762228] Key type ._llcrypt registered [ 5211.770114] Key type .llcrypt registered [ 5213.129866] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 22:23:06 (1787710986) [ 5222.207531] Key type .llcrypt unregistered [ 5222.209716] Key type ._llcrypt unregistered [ 5230.337892] Key type ._llcrypt registered [ 5230.345621] Key type .llcrypt registered [ 5230.947318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5230.964534] alg: No test for adler32 (adler32-zlib) [ 5232.027203] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 5232.320207] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 5234.032185] Key type lgssc registered [ 5235.181198] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5318.791636] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5350.148778] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5355.791089] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5401.036181] Key type lgssc unregistered [ 5401.222617] LNet: 126302:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5401.232616] LNetError: 126302:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5401.255261] LNet: Removed LNI 192.168.206.27@tcp [ 5402.034710] Key type .llcrypt unregistered [ 5402.036957] Key type ._llcrypt unregistered [ 5415.215649] Key type ._llcrypt registered [ 5415.218220] Key type .llcrypt registered [ 5416.452836] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 22:26:30 (1787711190) [ 5418.663447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5418.846260] alg: No test for adler32 (adler32-zlib) [ 5419.852887] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 5420.027325] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 5421.658470] Key type lgssc registered [ 5422.963146] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5473.067724] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5478.495505] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5497.507320] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5498.970578] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 5503.975029] Lustre: lustre-MDT0000-mdc-ffff8b7112ccf000: Connection to lustre-MDT0000 (at 192.168.206.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5520.352558] Lustre: 127278:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787711279/real 1787711279] req@00000000918c15dc x1874551054011264/t0(0) o400->MGC192.168.206.127@tcp@192.168.206.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1787711295 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5520.365166] LustreError: MGC192.168.206.127@tcp: Connection to MGS (at 192.168.206.127@tcp) was lost; in progress operations using this service will fail [ 5525.945291] Lustre: lustre-MDT0000-mdc-ffff8b7112ccf000: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 5530.661447] Lustre: Evicted from MGS (at 192.168.206.127@tcp) after server handle changed from 0x5350cd1a14260baf to 0x5350cd1a14260e95 [ 5530.673326] Lustre: MGC192.168.206.127@tcp: Connection restored to 192.168.206.127@tcp (at 192.168.206.127@tcp) [ 5542.792243] Lustre: Unmounted lustre-client [ 5564.196948] Key type lgssc unregistered [ 5564.453253] LNet: 130279:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5564.464965] LNetError: 130279:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5564.488552] LNet: Removed LNI 192.168.206.27@tcp [ 5565.071465] Key type .llcrypt unregistered [ 5565.074243] Key type ._llcrypt unregistered [ 5577.661872] Key type ._llcrypt registered [ 5577.664679] Key type .llcrypt registered [ 5578.824498] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 22:29:12 (1787711352) [ 5580.684378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5580.711288] alg: No test for adler32 (adler32-zlib) [ 5581.745350] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 5581.955694] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 5583.632200] Key type lgssc registered [ 5584.691678] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5632.636296] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5638.728507] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5658.911107] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5661.553507] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 5676.688308] Lustre: Unmounted lustre-client [ 5677.225105] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 5693.084837] Lustre: Unmounted lustre-client [ 5719.463886] Key type lgssc unregistered [ 5719.703084] LNet: 134505:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5719.708629] LNetError: 134505:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5719.732893] LNet: Removed LNI 192.168.206.27@tcp [ 5720.321683] Key type .llcrypt unregistered [ 5720.324313] Key type ._llcrypt unregistered [ 5731.210553] Key type ._llcrypt registered [ 5731.214465] Key type .llcrypt registered [ 5732.502436] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 22:31:46 (1787711506) [ 5735.364863] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5735.395032] alg: No test for adler32 (adler32-zlib) [ 5736.512635] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 5736.829220] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 5738.496890] Key type lgssc registered [ 5739.681559] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5783.921708] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5789.085784] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5810.148232] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5811.731067] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 5845.764955] Lustre: Unmounted lustre-client [ 5873.158195] Key type lgssc unregistered [ 5873.424127] LNet: 137863:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5873.434461] LNetError: 137863:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5873.460726] LNet: Removed LNI 192.168.206.27@tcp [ 5874.428995] Key type .llcrypt unregistered [ 5874.431518] Key type ._llcrypt unregistered [ 5889.159783] Key type ._llcrypt registered [ 5889.161035] Key type .llcrypt registered [ 5890.170484] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 22:34:24 (1787711664) [ 5892.424938] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5892.538082] alg: No test for adler32 (adler32-zlib) [ 5893.539428] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 5893.715306] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 5895.368152] Key type lgssc registered [ 5896.659910] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5947.818757] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5953.412250] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5975.108564] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5976.918724] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 5979.270175] Lustre: Unmounted lustre-client [ 5979.704582] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 5987.362251] Lustre: Unmounted lustre-client [ 6014.609290] Key type lgssc unregistered [ 6014.860305] LNet: 141904:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6014.878442] LNetError: 141904:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6014.896243] LNet: Removed LNI 192.168.206.27@tcp [ 6015.570465] Key type .llcrypt unregistered [ 6015.577310] Key type ._llcrypt unregistered [ 6026.152761] Key type ._llcrypt registered [ 6026.157320] Key type .llcrypt registered [ 6027.442945] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 22:36:41 (1787711801) [ 6030.087198] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6030.315242] alg: No test for adler32 (adler32-zlib) [ 6031.390334] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 6031.694596] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 6033.432751] Key type lgssc registered [ 6034.902661] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6084.752373] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6090.802718] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6112.969281] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6114.727156] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 6117.183480] Lustre: Unmounted lustre-client [ 6117.701649] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 6125.736355] Lustre: Unmounted lustre-client [ 6161.591821] Key type lgssc unregistered [ 6161.843794] LNet: 145528:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6161.851390] LNetError: 145528:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6161.865655] LNet: Removed LNI 192.168.206.27@tcp [ 6162.572932] Key type .llcrypt unregistered [ 6162.576744] Key type ._llcrypt unregistered [ 6173.284855] Key type ._llcrypt registered [ 6173.286642] Key type .llcrypt registered [ 6174.524615] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 22:39:08 (1787711948) [ 6177.194456] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6177.224188] alg: No test for adler32 (adler32-zlib) [ 6178.383347] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 6178.724822] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 6180.426650] Key type lgssc registered [ 6181.394873] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6234.473266] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6240.078148] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6259.523897] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6262.384127] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 6282.142190] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6283.831289] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6302.738382] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6312.991652] Lustre: Unmounted lustre-client [ 6313.299254] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6313.438581] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 6321.030368] Lustre: Unmounted lustre-client [ 6364.631546] Key type lgssc unregistered [ 6364.859926] LNet: 149576:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6364.864808] LNetError: 149576:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6364.880148] LNet: Removed LNI 192.168.206.27@tcp [ 6365.576544] Key type .llcrypt unregistered [ 6365.585865] Key type ._llcrypt unregistered [ 6394.298981] Key type ._llcrypt registered [ 6394.301840] Key type .llcrypt registered [ 6395.510208] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 22:42:49 (1787712169) [ 6397.826810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6397.856453] alg: No test for adler32 (adler32-zlib) [ 6398.892187] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 6399.091269] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 6400.736169] Key type lgssc registered [ 6402.074415] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6449.612322] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6455.418369] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6475.939095] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6478.612144] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 6641.887073] Lustre: Unmounted lustre-client [ 6642.397449] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 6645.805287] Lustre: Unmounted lustre-client [ 6646.316025] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 6648.800883] Lustre: Unmounted lustre-client [ 6676.028495] Key type lgssc unregistered [ 6676.265435] LNet: 154854:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6676.283698] LNetError: 154854:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6676.300465] LNet: Removed LNI 192.168.206.27@tcp [ 6676.907197] Key type .llcrypt unregistered [ 6676.917755] Key type ._llcrypt unregistered [ 6691.402395] Key type ._llcrypt registered [ 6691.404511] Key type .llcrypt registered [ 6692.724889] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 22:47:46 (1787712466) [ 6694.977095] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6695.002837] alg: No test for adler32 (adler32-zlib) [ 6696.055809] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 6696.246422] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 6697.876390] Key type lgssc registered [ 6698.894934] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6743.914436] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6748.921409] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6766.995254] Lustre: DEBUG MARKER: oleg627-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6769.779164] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 6779.123466] Lustre: Unmounted lustre-client [ 6779.547618] Lustre: Mounted lustre-client - version 2.17.57_80_gcb5c033 [ 6781.528623] Lustre: Unmounted lustre-client [ 6802.263219] Key type lgssc unregistered [ 6802.544912] LNet: 158568:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6802.565879] LNetError: 158568:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6802.591512] LNet: Removed LNI 192.168.206.27@tcp [ 6803.274662] Key type .llcrypt unregistered [ 6803.276696] Key type ._llcrypt unregistered [ 6817.114139] Key type ._llcrypt registered [ 6817.118758] Key type .llcrypt registered [ 6818.459273] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 22:49:51 (1787712591) [ 6819.469760] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6819.495426] alg: No test for adler32 (adler32-zlib) [ 6820.546698] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 6820.744551] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 6822.392499] Key type lgssc registered [ 6823.612936] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6845.282805] LustreError: 160264:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 6850.528656] LustreError: 160274:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 6898.592545] LNet: 1 local NIs in recovery (showing 1): 192.168.206.27@tcp [ 6973.344233] LNet: 1 local NIs in recovery (showing 1): 192.168.206.27@tcp [ 7004.128937] 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 [ 7004.142558] Lustre: Unmounted lustre-client [ 7004.176148] LustreError: 160264:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 7015.487721] Key type lgssc unregistered [ 7027.040540] LNet: 160682:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7027.043758] LNetError: 160682:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7103.846828] LNet: Removed LNI 192.168.206.27@tcp [ 7104.502562] Key type .llcrypt unregistered [ 7104.506503] Key type ._llcrypt unregistered [ 7136.787820] Key type ._llcrypt registered [ 7136.789796] Key type .llcrypt registered [ 7137.175183] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7137.233700] alg: No test for adler32 (adler32-zlib) [ 7138.311806] Lustre: Lustre: Build Version: 2.17.57_80_gcb5c033 [ 7138.599856] LNet: Added LNI 192.168.206.27@tcp [8/256/0/180] [ 7140.320235] Key type lgssc registered [ 7141.573479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7185.543549] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 6918 sec ========== 22:55:58 (1787712958) [ 7187.712807] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 22:56:00 (1787712960) === [ 7193.116597] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 22:56:06 (1787712966) === [ 7227.104494] Key type lgssc unregistered [ 7227.308190] LNet: 164926:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7227.316770] LNetError: 164926:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7227.330785] LNet: Removed LNI 192.168.206.27@tcp [ 7228.008613] Key type .llcrypt unregistered [ 7228.013393] Key type ._llcrypt unregistered