[ 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 513236311 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, 524588K 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.001013] APIC: Switch to symmetric I/O mode setup [ 0.002379] x2apic enabled [ 0.003007] Switched APIC routing to physical x2apic. [ 0.004012] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008009] pid_max: default: 32768 minimum: 301 [ 0.009126] LSM: Security Framework initializing [ 0.010040] Yama: becoming mindful. [ 0.011030] SELinux: Initializing. [ 0.012062] *** VALIDATE selinux *** [ 0.019742] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023759] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024137] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.025100] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026100] *** VALIDATE tmpfs *** [ 0.027418] *** VALIDATE proc *** [ 0.028206] *** VALIDATE cgroup *** [ 0.029006] *** VALIDATE cgroup2 *** [ 0.030242] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032054] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033005] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034028] Spectre V2 : User space: Vulnerable [ 0.035006] Speculative Store Bypass: Vulnerable [ 0.037948] debug: unmapping init [mem 0xffffffffa9259000-0xffffffffa9260fff] [ 0.039163] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.040651] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.041017] ... version: 2 [ 0.042010] ... bit width: 48 [ 0.043008] ... generic registers: 4 [ 0.044009] ... value mask: 0000ffffffffffff [ 0.045009] ... max period: 00007fffffffffff [ 0.046009] ... fixed-purpose events: 3 [ 0.047007] ... event mask: 000000070000000f [ 0.048279] rcu: Hierarchical SRCU implementation. [ 0.050355] smp: Bringing up secondary CPUs ... [ 0.051521] x86: Booting SMP configuration: [ 0.052016] .... node #0, CPUs: #1 #2 #3 [ 0.055586] smp: Brought up 1 node, 4 CPUs [ 0.057011] smpboot: Max logical packages: 1 [ 0.058017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.142000] node 0 deferred pages initialised in 80ms [ 0.145173] devtmpfs: initialized [ 0.147216] x86/mm: Memory block size: 128MB [ 0.150747] gcov: version magic: 0x41383552 [ 0.153187] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.156063] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.158255] pinctrl core: initialized pinctrl subsystem [ 0.160124] [ 0.160674] ************************************************************* [ 0.163010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.165008] ** ** [ 0.167010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.170009] ** ** [ 0.172008] ** This means that this kernel is built to expose internal ** [ 0.174008] ** IOMMU data structures, which may compromise security on ** [ 0.176009] ** your system. ** [ 0.178010] ** ** [ 0.180008] ** If you see this message and you are not debugging the ** [ 0.182010] ** kernel, report this immediately to your vendor! ** [ 0.185013] ** ** [ 0.187007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.189010] ************************************************************* [ 0.191578] NET: Registered protocol family 16 [ 0.193408] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.196044] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.198042] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.202035] cpuidle: using governor menu [ 0.204405] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.205371] PCI: Using configuration type 1 for base access [ 0.207111] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.217053] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.219016] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.223049] cryptd: max_cpu_qlen set to 1000 [ 0.226225] ACPI: Added _OSI(Module Device) [ 0.228009] ACPI: Added _OSI(Processor Device) [ 0.229009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.231008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.236184] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.241564] ACPI: Interpreter enabled [ 0.243048] ACPI: PM: (supports S0 S3 S4 S5) [ 0.245015] ACPI: Using IOAPIC for interrupt routing [ 0.247141] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.250332] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.260851] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.263029] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.265013] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.268060] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.272257] acpiphp: Slot [2] registered [ 0.274081] acpiphp: Slot [5] registered [ 0.275080] acpiphp: Slot [6] registered [ 0.276074] acpiphp: Slot [3] registered [ 0.278056] acpiphp: Slot [4] registered [ 0.279065] acpiphp: Slot [7] registered [ 0.280082] acpiphp: Slot [8] registered [ 0.281041] acpiphp: Slot [9] registered [ 0.282011] acpiphp: Slot [10] registered [ 0.283008] acpiphp: Slot [11] registered [ 0.285056] acpiphp: Slot [12] registered [ 0.286102] acpiphp: Slot [13] registered [ 0.288068] acpiphp: Slot [14] registered [ 0.289060] acpiphp: Slot [15] registered [ 0.291075] acpiphp: Slot [16] registered [ 0.292101] acpiphp: Slot [17] registered [ 0.294095] acpiphp: Slot [18] registered [ 0.295078] acpiphp: Slot [19] registered [ 0.297079] acpiphp: Slot [20] registered [ 0.298063] acpiphp: Slot [21] registered [ 0.300051] acpiphp: Slot [22] registered [ 0.301000] acpiphp: Slot [23] registered [ 0.303073] acpiphp: Slot [24] registered [ 0.305091] acpiphp: Slot [25] registered [ 0.306076] acpiphp: Slot [26] registered [ 0.307079] acpiphp: Slot [27] registered [ 0.309068] acpiphp: Slot [28] registered [ 0.310089] acpiphp: Slot [29] registered [ 0.312054] acpiphp: Slot [30] registered [ 0.313093] acpiphp: Slot [31] registered [ 0.314055] PCI host bridge to bus 0000:00 [ 0.315011] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.317014] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.319015] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.321015] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.323054] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.326018] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.328153] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.331054] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.334411] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.341592] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.344519] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.347013] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.350014] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.353014] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.354558] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.357875] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.361046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.364856] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.368931] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.379012] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.384017] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.388809] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.409016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.427015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.484025] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.510372] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.538021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.560026] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.602022] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.623560] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.625371] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.628394] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.630405] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.632265] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.637086] iommu: Default domain type: Passthrough [ 0.639115] SCSI subsystem initialized [ 0.640127] ACPI: bus type USB registered [ 0.641139] usbcore: registered new interface driver usbfs [ 0.643085] usbcore: registered new interface driver hub [ 0.645084] usbcore: registered new device driver usb [ 0.646165] pps_core: LinuxPPS API ver. 1 registered [ 0.648012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.651068] PTP clock support registered [ 0.653055] EDAC MC: Ver: 3.0.0 [ 0.654462] PCI: Using ACPI for IRQ routing [ 0.655742] NetLabel: Initializing [ 0.657009] NetLabel: domain hash size = 128 [ 0.658008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.661129] NetLabel: unlabeled traffic allowed by default [ 0.663261] vgaarb: loaded [ 0.665036] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.666009] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.673397] clocksource: Switched to clocksource kvm-clock [ 0.776626] VFS: Disk quotas dquot_6.6.0 [ 0.778087] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.780484] *** VALIDATE ramfs *** [ 0.781726] *** VALIDATE hugetlbfs *** [ 0.783161] pnp: PnP ACPI init [ 0.785465] pnp: PnP ACPI: found 6 devices [ 0.801432] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.803993] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.806013] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.808039] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.810296] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.812010] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.813879] NET: Registered protocol family 2 [ 0.815944] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.819845] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.822673] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.827212] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.830168] TCP: Hash tables configured (established 65536 bind 65536) [ 0.832543] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.835578] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.838244] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.840817] NET: Registered protocol family 1 [ 0.843720] RPC: Registered named UNIX socket transport module. [ 0.845245] RPC: Registered udp transport module. [ 0.846552] RPC: Registered tcp transport module. [ 0.847874] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.849484] NET: Registered protocol family 44 [ 0.850564] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.852036] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.853444] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.855384] PCI: CLS 0 bytes, default 64 [ 0.856725] Unpacking initramfs... [ 2.239975] debug: unmapping init [mem 0xffff8bccbcc64000-0xffff8bccbffcffff] [ 2.243612] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.245842] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.248410] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.811134] Initialise system trusted keyrings [ 2.812398] Key type blacklist registered [ 2.814965] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.823984] zbud: loaded [ 2.827188] *** VALIDATE nfs *** [ 2.828236] *** VALIDATE nfs4 *** [ 2.829983] pstore: using deflate compression [ 2.833509] Platform Keyring initialized [ 2.952814] NET: Registered protocol family 38 [ 2.954010] Key type asymmetric registered [ 2.954975] Asymmetric key parser 'x509' registered [ 2.956276] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.959164] io scheduler mq-deadline registered [ 2.960954] io scheduler kyber registered [ 2.962749] io scheduler bfq registered [ 2.966187] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.968841] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.971899] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.973913] ACPI: Power Button [PWRF] [ 2.979558] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.984970] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.006429] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.036705] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.066155] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.071725] Non-volatile memory driver v1.3 [ 3.073480] Linux agpgart interface v0.103 [ 3.103287] virtio_blk virtio1: [vda] 145920 512-byte logical blocks (74.7 MB/71.3 MiB) [ 3.106385] vda: detected capacity change from 0 to 74711040 [ 3.151349] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.154800] vdb: detected capacity change from 0 to 1073741824 [ 3.178799] libphy: Fixed MDIO Bus: probed [ 3.186492] usbcore: registered new interface driver usbserial_generic [ 3.189731] usbserial: USB Serial support registered for generic [ 3.192453] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.197464] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.199735] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.203227] mousedev: PS/2 mouse device common for all mice [ 3.206689] rtc_cmos 00:05: RTC can wake from S4 [ 3.210541] rtc_cmos 00:05: registered as rtc0 [ 3.212831] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.215772] intel_pstate: CPU model not supported [ 3.217813] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.222071] hid: raw HID events driver (C) Jiri Kosina [ 3.224541] usbcore: registered new interface driver usbhid [ 3.226451] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.226994] usbhid: USB HID core driver [ 3.231930] drop_monitor: Initializing network drop monitor service [ 3.231990] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.233797] Initializing XFRM netlink socket [ 3.238930] NET: Registered protocol family 10 [ 3.242750] Segment Routing with IPv6 [ 3.244079] NET: Registered protocol family 17 [ 3.246315] mpls_gso: MPLS GSO support [ 3.253238] RAS: Correctable Errors collector initialized. [ 3.255188] AVX version of gcm_enc/dec engaged. [ 3.256985] AES CTR mode by8 optimization enabled [ 3.350585] sched_clock: Marking stable (3350495882, 0)->(4244006380, -893510498) [ 3.354931] registered taskstats version 1 [ 3.358289] Loading compiled-in X.509 certificates [ 3.360433] zswap: loaded using pool lzo/zbud [ 3.389318] Key type big_key registered [ 3.405290] Key type encrypted registered [ 3.406827] ima: No TPM chip found, activating TPM-bypass! [ 3.408837] ima: Allocated hash algorithm: sha1 [ 3.410452] ima: No architecture policies found [ 3.412344] evm: Initialising EVM extended attributes: [ 3.414257] evm: security.selinux [ 3.415555] evm: security.ima [ 3.416784] evm: security.capability [ 3.418068] evm: HMAC attrs: 0x1 [ 3.420372] rtc_cmos 00:05: setting system clock to 2026-07-30 07:28:16 UTC (1785396496) [ 3.426545] debug: unmapping init [mem 0xffffffffaa203000-0xffffffffaa3fffff] [ 3.429417] debug: unmapping init [mem 0xffffffffa8f82000-0xffffffffa9258fff] [ 3.438203] Write protecting the kernel read-only data: 28672k [ 3.443033] debug: unmapping init [mem 0xffffffffa7603000-0xffffffffa77fffff] [ 3.446082] debug: unmapping init [mem 0xffffffffa7f14000-0xffffffffa7ffffff] [ 3.476696] 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.485946] systemd[1]: Detected virtualization kvm. [ 3.487678] systemd[1]: Detected architecture x86-64. [ 3.489534] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.518339] systemd[1]: No hostname configured. [ 3.519985] systemd[1]: Set hostname to . [ 3.522197] random: systemd: uninitialized urandom read (16 bytes read) [ 3.525481] systemd[1]: Initializing machine ID from random generator. [ 3.658944] random: systemd: uninitialized urandom read (16 bytes read) [ 3.661935] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.667125] random: systemd: uninitialized urandom read (16 bytes read) [ 3.669902] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.675631] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Journal Service... Starting Apply Kernel Variables... [ OK ] Reached target Timers. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. Starting Setup Virtual Console... [ OK ] Reached target Paths. [ OK ] Reached target Swap. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.265555] device-mapper: uevent: version 1.0.3 [ 4.268124] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. [ 4.507676] random: fast init done Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.993076] virtio_net virtio0 ens2: renamed from eth0 [ 5.239165] scsi host0: ata_piix [ 5.258200] scsi host1: ata_piix [ 5.263273] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.265601] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.926103] random: crng init done [ 9.927799] random: 7 urandom warning(s) missed due to ratelimiting [ 13.564526] dracut-initqueue[590]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 15.957990] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ 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 Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 19.814223] printk: systemd: 24 output lines suppressed due to ratelimiting [ 21.584093] SELinux: Disabled at runtime. [ 21.958832] 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) [ 21.982493] systemd[1]: Detected virtualization kvm. [ 21.988226] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 24.897856] systemd[1]: initrd-switch-root.service: Succeeded. [ 24.908065] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 24.934560] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 24.944902] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 24.960772] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 24.993725] systemd[1]: Starting Journal Service... Starting Journal Service... [ 25.039981] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Remount Root and Kernel File Systems... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-getty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting Huge Pages File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on Process Core Dump Socket. Starting Apply Kernel Variables... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Reached target Local Encrypted Volumes. Mounting POSIX Message Queue File System... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Activating swap /dev/disk/by-label/SWAP... Mounting Kernel Debug File System... [ 25.703265] 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 Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Journal Service. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... Starting Create Static Device Nodes in /dev... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 27.281654] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 29.134366] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 29.241386] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 29.689333] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 29.761214] EDAC sbridge: Ver: 1.1.2 [ 32.308929] Key type dns_resolver registered [ 32.895703] hrtimer: interrupt took 3692846 ns [ 32.997962] NFS: Registering the id_resolver key type [ 33.005519] Key type id_resolver registered [ 33.010674] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ 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... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ 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 Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... Starting Network Manager... Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Login 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 Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg213-client login: [ 137.739327] libcfs: loading out-of-tree module taints kernel. [ 138.234551] Key type ._llcrypt registered [ 138.241126] Key type .llcrypt registered [ 138.873680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 138.902770] alg: No test for adler32 (adler32-zlib) [ 140.924782] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 142.314333] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 144.279148] Key type lgssc registered [ 146.979479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 382.157408] Lustre: Mounted lustre-client [ 392.286310] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 407.522664] Lustre: lustre-OST0000-osc-ffff8bcd09006800: disconnect after 22s idle [ 414.134912] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing check_logdir /tmp/testlogs/ [ 423.414901] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing yml_node [ 430.463702] Lustre: DEBUG MARKER: Client: 2.17.56.2 [ 435.158095] Lustre: DEBUG MARKER: MDS: 2.17.56.2 [ 438.694237] Lustre: DEBUG MARKER: OSS: 2.17.56.2 [ 440.996429] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Jul 30 03:35:31 EDT 2026 [ 464.155469] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 466.166898] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 472.049561] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 482.469746] Lustre: Unmounted lustre-client [ 647.239298] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 654.949716] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 685.485798] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 740.578482] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 03:40:31 (1785397231) [ 792.873970] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 801.224401] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 829.279484] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 830.247433] Lustre: Mounted lustre-client [ 837.521009] Lustre: Unmounted lustre-client [ 869.619366] Key type lgssc unregistered [ 870.000086] LNet: 14719:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 870.014167] LNetError: 14719:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 870.033300] LNet: Removed LNI 192.168.202.13@tcp [ 871.130136] Key type .llcrypt unregistered [ 871.131832] Key type ._llcrypt unregistered [ 895.912595] Key type ._llcrypt registered [ 895.917992] Key type .llcrypt registered [ 899.227204] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 03:43:08 (1785397388) [ 901.912499] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 901.929942] alg: No test for adler32 (adler32-zlib) [ 903.256838] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 903.829134] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 905.463180] Key type lgssc registered [ 907.803611] Lustre: Echo OBD driver; http://www.lustre.org/ [ 975.857788] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 983.041133] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1011.166660] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1026.278030] Lustre: Mounted lustre-client [ 1029.382196] Lustre: Unmounted lustre-client [ 1062.183556] Key type lgssc unregistered [ 1062.791983] LNet: 18405:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1062.805240] LNetError: 18405:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1062.837132] LNet: Removed LNI 192.168.202.13@tcp [ 1064.445442] Key type .llcrypt unregistered [ 1064.456543] Key type ._llcrypt unregistered [ 1100.828283] Key type ._llcrypt registered [ 1100.831940] Key type .llcrypt registered [ 1103.009406] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 03:46:33 (1785397593) [ 1106.546122] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1106.572291] alg: No test for adler32 (adler32-zlib) [ 1107.733619] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 1108.140129] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 1109.911292] Key type lgssc registered [ 1111.879174] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1182.696145] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1193.328707] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1238.153622] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1244.303344] Lustre: Mounted lustre-client [ 1249.474159] Lustre: Unmounted lustre-client [ 1285.016131] Key type lgssc unregistered [ 1285.368425] LNet: 22069:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1285.383779] LNetError: 22069:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1285.407967] LNet: Removed LNI 192.168.202.13@tcp [ 1286.524276] Key type .llcrypt unregistered [ 1286.531036] Key type ._llcrypt unregistered [ 1308.763203] Key type ._llcrypt registered [ 1308.766921] Key type .llcrypt registered [ 1310.416874] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 03:50:01 (1785397801) [ 1313.193401] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1313.285590] alg: No test for adler32 (adler32-zlib) [ 1314.607490] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 1315.008813] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 1316.783175] Key type lgssc registered [ 1318.948766] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1393.758550] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1403.551482] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1434.144687] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1436.071943] Lustre: Mounted lustre-client [ 1443.171395] Lustre: Unmounted lustre-client [ 1480.144611] Key type lgssc unregistered [ 1480.941710] LNet: 25689:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1480.950232] LNetError: 25689:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1481.005658] LNet: Removed LNI 192.168.202.13@tcp [ 1482.563746] Key type .llcrypt unregistered [ 1482.570444] Key type ._llcrypt unregistered [ 1508.983623] Key type ._llcrypt registered [ 1508.985607] Key type .llcrypt registered [ 1510.628114] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 03:53:21 (1785398001) [ 1513.222766] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1513.258812] alg: No test for adler32 (adler32-zlib) [ 1514.623227] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 1514.870413] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 1516.519173] Key type lgssc registered [ 1518.280469] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1586.064647] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1594.459946] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1624.134451] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1626.425073] Lustre: Mounted lustre-client [ 1636.859193] Lustre: lustre-OST0000-osc-ffff8bcd10d9d000: Connection to lustre-OST0000 (at 192.168.202.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1644.298629] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1654.675162] Lustre: Unmounted lustre-client [ 1688.664695] Key type lgssc unregistered [ 1689.006814] LNet: 29318:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1689.014489] LNetError: 29318:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1689.034198] LNet: Removed LNI 192.168.202.13@tcp [ 1690.087986] Key type .llcrypt unregistered [ 1690.091230] Key type ._llcrypt unregistered [ 1710.669429] Key type ._llcrypt registered [ 1710.673405] Key type .llcrypt registered [ 1712.417103] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 03:56:43 (1785398203) [ 1715.751409] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1715.775116] alg: No test for adler32 (adler32-zlib) [ 1717.183224] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 1717.599205] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 1719.432126] Key type lgssc registered [ 1721.434896] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1794.946859] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1803.019439] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1832.239843] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1835.177831] Lustre: Mounted lustre-client [ 1845.732908] Lustre: lustre-MDT0000-mdc-ffff8bcd0913e800: Connection to lustre-MDT0000 (at 192.168.202.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1855.988013] Lustre: lustre-MDT0001-mdc-ffff8bcd0913e800: Connection to lustre-MDT0001 (at 192.168.202.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1863.463381] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1866.209444] Lustre: 30278:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785398343/real 1785398343] req@00000000e84fafa5 x1872125713455232/t0(0) o400->MGC192.168.202.113@tcp@192.168.202.113@tcp:26/25 lens 224/224 e 0 to 1 dl 1785398359 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1866.268328] LustreError: MGC192.168.202.113@tcp: Connection to MGS (at 192.168.202.113@tcp) was lost; in progress operations using this service will fail [ 1873.899732] Lustre: Unmounted lustre-client [ 1891.706828] Key type lgssc unregistered [ 1892.310657] LNet: 32981:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1892.316275] LNetError: 32981:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1892.352104] LNet: Removed LNI 192.168.202.13@tcp [ 1893.554566] Key type .llcrypt unregistered [ 1893.562339] Key type ._llcrypt unregistered [ 1917.133457] Key type ._llcrypt registered [ 1917.137135] Key type .llcrypt registered [ 1919.135602] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 04:00:09 (1785398409) [ 1922.859882] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1923.212823] alg: No test for adler32 (adler32-zlib) [ 1924.643513] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 1925.229037] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 1927.031767] Key type lgssc registered [ 1929.195459] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2031.708910] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2037.535851] LustreError: 35201:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.202.113@tcp: failed processing log, type 1: rc = -110 [ 2042.848362] LustreError: 35210:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 2195.423606] LustreError: MGC192.168.202.113@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 [ 2195.472631] Lustre: Unmounted lustre-client [ 2195.492990] LustreError: 35201:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 2215.598744] Key type lgssc unregistered [ 2215.867056] LNet: 35627:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2215.874609] LNetError: 35627:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2215.906817] LNet: Removed LNI 192.168.202.13@tcp [ 2216.806851] Key type .llcrypt unregistered [ 2216.808177] Key type ._llcrypt unregistered [ 2235.709065] Key type ._llcrypt registered [ 2235.710487] Key type .llcrypt registered [ 2237.646550] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 04:05:28 (1785398728) [ 2240.910951] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2241.083858] alg: No test for adler32 (adler32-zlib) [ 2242.385394] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 2242.959597] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 2244.663181] Key type lgssc registered [ 2246.440423] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2315.466907] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2322.849057] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2349.945775] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2350.665705] LustreError: 38728:0:(llite_lib.c:1564:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 2350.675715] Lustre: Unmounted wrong.lustre-client [ 2350.688904] LustreError: 38728:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 2385.242833] Key type lgssc unregistered [ 2385.825627] LNet: 39145:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2385.836633] LNetError: 39145:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2386.859123] LNet: Removed LNI 192.168.202.13@tcp [ 2388.012214] Key type .llcrypt unregistered [ 2388.017071] Key type ._llcrypt unregistered [ 2412.741248] Key type ._llcrypt registered [ 2412.743263] Key type .llcrypt registered [ 2414.727979] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 04:08:25 (1785398905) [ 2418.093100] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2418.295935] alg: No test for adler32 (adler32-zlib) [ 2419.415524] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 2419.797193] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 2421.681066] Key type lgssc registered [ 2423.775189] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2521.621697] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2581.200490] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2587.869891] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2602.028671] Lustre: Mounted lustre-client [ 2606.458669] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2616.870500] Lustre: Unmounted lustre-client [ 2654.017139] Key type lgssc unregistered [ 2654.458647] LNet: 42729:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2654.467129] LNetError: 42729:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2654.493832] LNet: Removed LNI 192.168.202.13@tcp [ 2655.718942] Key type .llcrypt unregistered [ 2655.739516] Key type ._llcrypt unregistered [ 2680.881543] Key type ._llcrypt registered [ 2680.883191] Key type .llcrypt registered [ 2682.690570] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 04:12:53 (1785399173) [ 2689.806734] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2689.858565] alg: No test for adler32 (adler32-zlib) [ 2691.894659] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 2692.425706] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 2694.871187] Key type lgssc registered [ 2699.186800] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2774.322474] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2783.462376] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2812.473591] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2814.176859] LustreError: 43693:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2818.275788] LustreError: 45822:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.202.113@tcp: failed processing log, type 1: rc = -110 [ 2823.651167] LustreError: 45831:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 2824.207694] LustreError: 43693:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2831.111828] Lustre: Mounted lustre-client [ 2835.627450] Lustre: Unmounted lustre-client [ 2886.628960] Key type lgssc unregistered [ 2886.933721] LNet: 46284:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2886.943400] LNetError: 46284:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2887.004307] LNet: Removed LNI 192.168.202.13@tcp [ 2889.180856] Key type .llcrypt unregistered [ 2889.195331] Key type ._llcrypt unregistered [ 2923.513446] Key type ._llcrypt registered [ 2923.514740] Key type .llcrypt registered [ 2926.303780] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 04:16:56 (1785399416) [ 2929.630986] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2932.551741] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 04:17:03 (1785399423) [ 2935.045871] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2935.064137] alg: No test for adler32 (adler32-zlib) [ 2936.368369] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 2936.875920] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 2938.664058] Key type lgssc registered [ 2940.428163] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2973.740789] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 04:17:44 (1785399464) [ 3031.686951] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3040.092909] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3071.277395] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3074.292521] Lustre: Mounted lustre-client [ 3084.797867] Lustre: lustre-MDT0000-mdc-ffff8bcd1203d000: Connection to lustre-MDT0000 (at 192.168.202.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3100.127142] Lustre: 47513:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785399577/real 1785399577] req@00000000d7c3767f x1872126991667328/t0(0) o400->MGC192.168.202.113@tcp@192.168.202.113@tcp:26/25 lens 224/224 e 0 to 1 dl 1785399593 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3100.189315] LustreError: MGC192.168.202.113@tcp: Connection to MGS (at 192.168.202.113@tcp) was lost; in progress operations using this service will fail [ 3110.421807] Lustre: Evicted from MGS (at 192.168.202.113@tcp) after server handle changed from 0xbcc52386f549c343 to 0xbcc52386f549c5b9 [ 3110.441385] Lustre: MGC192.168.202.113@tcp: Connection restored to 192.168.202.113@tcp (at 192.168.202.113@tcp) [ 3113.171484] Lustre: lustre-MDT0000-mdc-ffff8bcd1203d000: Connection restored to 192.168.202.113@tcp (at 192.168.202.113@tcp) [ 3125.561582] Lustre: Unmounted lustre-client [ 3158.297491] Key type lgssc unregistered [ 3158.590619] LNet: 51163:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3158.598552] LNetError: 51163:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3158.616626] LNet: Removed LNI 192.168.202.13@tcp [ 3159.393826] Key type .llcrypt unregistered [ 3159.401110] Key type ._llcrypt unregistered [ 3186.300312] Key type ._llcrypt registered [ 3186.304730] Key type .llcrypt registered [ 3188.140403] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 04:21:19 (1785399679) [ 3191.652674] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3191.942353] alg: No test for adler32 (adler32-zlib) [ 3193.310082] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 3193.544771] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 3195.255600] Key type lgssc registered [ 3196.986542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3274.667975] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3283.313065] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3318.419748] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3320.451985] Lustre: Mounted lustre-client [ 3330.560028] Lustre: lustre-MDT0000-mdc-ffff8bcd113b7800: Connection to lustre-MDT0000 (at 192.168.202.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3346.847173] Lustre: 52127:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785399823/real 1785399823] req@00000000aa42bc38 x1872127261151232/t0(0) o400->MGC192.168.202.113@tcp@192.168.202.113@tcp:26/25 lens 224/224 e 0 to 1 dl 1785399839 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3346.891504] LustreError: MGC192.168.202.113@tcp: Connection to MGS (at 192.168.202.113@tcp) was lost; in progress operations using this service will fail [ 3353.249181] Lustre: lustre-MDT0000-mdc-ffff8bcd113b7800: Connection restored to 192.168.202.113@tcp (at 192.168.202.113@tcp) [ 3356.323107] Lustre: Evicted from MGS (at 192.168.202.113@tcp) after server handle changed from 0x758874aeeb85098a to 0x758874aeeb850d34 [ 3356.359535] Lustre: MGC192.168.202.113@tcp: Connection restored to 192.168.202.113@tcp (at 192.168.202.113@tcp) [ 3363.503497] Lustre: Unmounted lustre-client [ 3405.457368] Key type lgssc unregistered [ 3405.992799] LNet: 55140:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3406.001746] LNetError: 55140:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3406.037036] LNet: Removed LNI 192.168.202.13@tcp [ 3407.190424] Key type .llcrypt unregistered [ 3407.199402] Key type ._llcrypt unregistered [ 3440.486693] Key type ._llcrypt registered [ 3440.488705] Key type .llcrypt registered [ 3442.862232] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 04:25:33 (1785399933) [ 3444.354644] Key type .llcrypt unregistered [ 3444.363958] Key type ._llcrypt unregistered [ 3456.090799] Key type ._llcrypt registered [ 3456.093594] Key type .llcrypt registered [ 3456.176642] Key type .llcrypt unregistered [ 3456.184544] Key type ._llcrypt unregistered [ 3462.930354] Key type ._llcrypt registered [ 3462.934656] Key type .llcrypt registered [ 3464.429913] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 04:25:55 (1785399955) [ 3467.970638] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3468.002377] alg: No test for adler32 (adler32-zlib) [ 3469.212865] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 3469.564143] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 3471.322769] Key type lgssc registered [ 3473.634073] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3558.474533] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3569.933345] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3597.442542] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3600.720982] Lustre: Mounted lustre-client [ 3606.322580] Lustre: Unmounted lustre-client [ 3606.877604] Lustre: Mounted lustre-client [ 3609.986981] Lustre: Unmounted lustre-client [ 3653.872429] Key type lgssc unregistered [ 3654.552854] LNet: 59568:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3654.564859] LNetError: 59568:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3654.588442] LNet: Removed LNI 192.168.202.13@tcp [ 3656.323953] Key type .llcrypt unregistered [ 3656.335655] Key type ._llcrypt unregistered [ 3684.486350] Key type ._llcrypt registered [ 3684.498398] Key type .llcrypt registered [ 3686.235185] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 04:29:37 (1785400177) [ 3689.092060] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3689.143734] alg: No test for adler32 (adler32-zlib) [ 3690.715863] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 3691.308569] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 3693.247174] Key type lgssc registered [ 3694.732879] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3773.037579] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3786.118940] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3820.726666] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3824.177838] Lustre: Mounted lustre-client [ 3826.595585] Lustre: Unmounted lustre-client [ 3868.418582] Key type lgssc unregistered [ 3869.037803] LNet: 63147:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3869.055986] LNetError: 63147:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3869.108889] LNet: Removed LNI 192.168.202.13@tcp [ 3871.015929] Key type .llcrypt unregistered [ 3871.017612] Key type ._llcrypt unregistered [ 3893.967884] Key type ._llcrypt registered [ 3893.975400] Key type .llcrypt registered [ 3896.091842] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 04:33:06 (1785400386) [ 3899.464618] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3899.660505] alg: No test for adler32 (adler32-zlib) [ 3900.999265] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 3901.546492] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 3903.370628] Key type lgssc registered [ 3905.976697] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3983.563846] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3992.950356] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4024.210844] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4026.390772] Lustre: Mounted lustre-client [ 4030.047181] Lustre: Mounted lustre-client [ 4034.587396] Lustre: Unmounted lustre-client [ 4038.715587] Lustre: Unmounted lustre-client [ 4071.717440] Key type lgssc unregistered [ 4072.073148] LNet: 66812:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4072.091785] LNetError: 66812:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4072.119579] LNet: Removed LNI 192.168.202.13@tcp [ 4073.534777] Key type .llcrypt unregistered [ 4073.538839] Key type ._llcrypt unregistered [ 4098.731863] Key type ._llcrypt registered [ 4098.735428] Key type .llcrypt registered [ 4101.432359] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 04:36:31 (1785400591) [ 4105.192153] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4105.256138] alg: No test for adler32 (adler32-zlib) [ 4106.798163] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 4107.340261] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 4109.231887] Key type lgssc registered [ 4111.605943] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4215.684126] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4234.087996] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 04:38:44 (1785400724) [ 4278.359151] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4285.306990] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4314.839326] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4317.752222] Lustre: Mounted lustre-client [ 4326.234671] Lustre: Unmounted lustre-client [ 4368.593592] Key type lgssc unregistered [ 4369.020813] LNet: 71590:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4369.035461] LNetError: 71590:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4369.066086] LNet: Removed LNI 192.168.202.13@tcp [ 4370.328449] Key type .llcrypt unregistered [ 4370.333534] Key type ._llcrypt unregistered [ 4395.447619] Key type ._llcrypt registered [ 4395.449064] Key type .llcrypt registered [ 4397.216375] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 04:41:28 (1785400888) [ 4422.340282] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 04:41:53 (1785400913) [ 4425.468468] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4425.607781] alg: No test for adler32 (adler32-zlib) [ 4426.824577] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 4427.097948] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 4428.903239] Key type lgssc registered [ 4430.543190] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4515.323291] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4529.042251] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4561.806870] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4563.794929] Lustre: Mounted lustre-client [ 4571.031472] Lustre: Unmounted lustre-client [ 4612.356121] Key type lgssc unregistered [ 4612.870530] LNet: 75905:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4612.876277] LNetError: 75905:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4612.917325] LNet: Removed LNI 192.168.202.13@tcp [ 4614.026517] Key type .llcrypt unregistered [ 4614.033863] Key type ._llcrypt unregistered [ 4642.213073] Key type ._llcrypt registered [ 4642.219980] Key type .llcrypt registered [ 4642.894392] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4642.948068] alg: No test for adler32 (adler32-zlib) [ 4644.129520] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 4644.523958] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 4646.311359] Key type lgssc registered [ 4647.537288] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4750.334246] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4944.537508] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4953.108430] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4985.002608] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5047.799939] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 04:52:18 (1785401538) [ 5054.221229] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 5222.278369] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5230.894689] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5266.906682] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5372.344638] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5382.670128] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5416.944669] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5419.020667] Lustre: Mounted lustre-client [ 5426.183390] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 5430.170634] Lustre: Unmounted lustre-client [ 5474.074083] Key type lgssc unregistered [ 5474.534443] LNet: 87641:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5474.555257] LNetError: 87641:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5474.593180] LNet: Removed LNI 192.168.202.13@tcp [ 5476.543868] Key type .llcrypt unregistered [ 5476.558912] Key type ._llcrypt unregistered [ 5531.924923] Key type ._llcrypt registered [ 5531.972787] Key type .llcrypt registered [ 5532.856341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5532.882869] alg: No test for adler32 (adler32-zlib) [ 5534.023874] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 5534.417434] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 5536.191209] Key type lgssc registered [ 5538.709111] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5660.730121] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5670.083101] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5707.033794] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5769.095680] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 05:04:19 (1785402259) [ 5817.996551] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5826.737410] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5856.679279] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 05:05:47 (1785402347) [ 5936.501771] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5955.776163] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 05:07:26 (1785402446) [ 6003.881363] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6011.223728] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6039.107863] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6040.722396] Lustre: Mounted lustre-client [ 6041.460935] Lustre: Remounted lustre-client read-only [ 6042.960183] Lustre: Unmounted lustre-client [ 6083.797092] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 05:09:34 (1785402574) [ 6141.361093] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6152.194880] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6194.840414] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6246.510827] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 05:12:17 (1785402737) [ 6327.311179] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6377.475422] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6388.212737] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6451.614720] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 05:15:42 (1785402942) [ 6530.243180] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6578.652687] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6588.741561] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6621.521852] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6710.186048] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 05:20:01 (1785403201) [ 6712.950271] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 6715.176908] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 6716.760629] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 05:20:08 (1785403208) [ 6760.502860] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6767.447608] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6794.091134] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6830.867001] Lustre: Mounted lustre-client [ 6834.900116] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6845.253669] Lustre: Unmounted lustre-client [ 6875.555067] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6876.479456] Lustre: Mounted lustre-client [ 6900.451706] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8bcd100a2000.ost_server_uuid 50 [ 6903.043792] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8bcd100a2000.ost_server_uuid in IDLE state after 0 sec [ 6906.739215] Lustre: Unmounted lustre-client [ 6955.739169] Key type lgssc unregistered [ 6956.192808] LNet: 108077:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6956.212963] LNetError: 108077:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6956.252166] LNet: Removed LNI 192.168.202.13@tcp [ 6957.523312] Key type .llcrypt unregistered [ 6957.526804] Key type ._llcrypt unregistered [ 6979.550237] Key type ._llcrypt registered [ 6979.551867] Key type .llcrypt registered [ 6980.634284] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 05:24:32 (1785403472) [ 6982.684033] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6982.691223] alg: No test for adler32 (adler32-zlib) [ 6983.994018] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 6984.223659] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 6985.871202] Key type lgssc registered [ 6987.026133] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7055.001610] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7062.577963] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7092.380691] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7095.436305] Lustre: Mounted lustre-client [ 7104.446549] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7114.782561] LustreError: MGC192.168.202.113@tcp: Connection to MGS (at 192.168.202.113@tcp) was lost; in progress operations using this service will fail [ 7114.865564] Lustre: Unmounted lustre-client [ 7141.888768] LustreError: lustre-MDT0000-mdc-ffff8bcd0695e800: operation mds_connect to node 192.168.202.113@tcp failed: rc = -16 [ 7146.954067] LustreError: 111464:0:(lmv_obd.c:1468:lmv_statfs()) lustre-MDT0000-mdc-ffff8bcd0695e800: can't stat MDS #0: rc = -16 [ 7147.100159] Lustre: Unmounted lustre-client [ 7147.126670] LustreError: 111464:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 7194.091551] Key type lgssc unregistered [ 7194.680716] LNet: 111922:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7194.685590] LNetError: 111922:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7194.717155] LNet: Removed LNI 192.168.202.13@tcp [ 7196.288139] Key type .llcrypt unregistered [ 7196.295414] Key type ._llcrypt unregistered [ 7221.938434] Key type ._llcrypt registered [ 7221.949538] Key type .llcrypt registered [ 7223.721608] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 05:28:34 (1785403714) [ 7227.171039] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7227.191088] alg: No test for adler32 (adler32-zlib) [ 7228.217575] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 7228.696763] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 7230.407195] Key type lgssc registered [ 7232.569504] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7306.886687] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7319.089945] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7353.636249] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7356.521084] Lustre: Mounted lustre-client [ 7360.854955] Lustre: Unmounted lustre-client [ 7399.365407] Key type lgssc unregistered [ 7400.118379] LNet: 115465:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7400.143317] LNetError: 115465:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7400.193196] LNet: Removed LNI 192.168.202.13@tcp [ 7401.737680] Key type .llcrypt unregistered [ 7401.750160] Key type ._llcrypt unregistered [ 7438.365987] Key type ._llcrypt registered [ 7438.371753] Key type .llcrypt registered [ 7440.387808] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 05:32:11 (1785403931) [ 7444.108936] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 7446.758060] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 05:32:17 (1785403937) [ 7450.268187] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 7452.552100] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 05:32:23 (1785403943) [ 7455.508251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7455.719849] alg: No test for adler32 (adler32-zlib) [ 7457.248569] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 7457.934651] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 7459.815833] Key type lgssc registered [ 7462.422406] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7538.667690] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7546.968316] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7578.132886] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7581.259797] Lustre: Mounted lustre-client [ 7587.762139] Lustre: Unmounted lustre-client [ 7628.880883] Key type lgssc unregistered [ 7629.315058] LNet: 119671:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7629.322488] LNetError: 119671:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7629.371636] LNet: Removed LNI 192.168.202.13@tcp [ 7631.042321] Key type .llcrypt unregistered [ 7631.043560] Key type ._llcrypt unregistered [ 7655.665444] Key type ._llcrypt registered [ 7655.667511] Key type .llcrypt registered [ 7656.724769] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 05:35:48 (1785404148) [ 7657.928125] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7657.954418] alg: No test for adler32 (adler32-zlib) [ 7659.292302] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 7659.810637] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 7661.719218] Key type lgssc registered [ 7662.928361] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7744.473464] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7752.026995] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7775.590629] Key type lgssc unregistered [ 7776.041351] LNet: 122761:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7776.047307] LNetError: 122761:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7776.085396] LNet: Removed LNI 192.168.202.13@tcp [ 7777.385299] Key type .llcrypt unregistered [ 7777.387876] Key type ._llcrypt unregistered [ 7803.877766] Key type ._llcrypt registered [ 7803.879496] Key type .llcrypt registered [ 7805.497387] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 05:38:16 (1785404296) [ 7816.897366] Key type .llcrypt unregistered [ 7816.898670] Key type ._llcrypt unregistered [ 7830.633905] Key type ._llcrypt registered [ 7830.636147] Key type .llcrypt registered [ 7831.546825] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7831.581145] alg: No test for adler32 (adler32-zlib) [ 7833.085652] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 7833.566739] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 7835.472095] Key type lgssc registered [ 7837.906570] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7948.870809] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8004.258115] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8013.159223] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8075.599712] Key type lgssc unregistered [ 8075.851205] LNet: 126632:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8075.858822] LNetError: 126632:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8075.887296] LNet: Removed LNI 192.168.202.13@tcp [ 8076.991510] Key type .llcrypt unregistered [ 8076.993401] Key type ._llcrypt unregistered [ 8115.702483] Key type ._llcrypt registered [ 8115.704026] Key type .llcrypt registered [ 8118.887844] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 05:43:28 (1785404608) [ 8124.864739] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8124.936288] alg: No test for adler32 (adler32-zlib) [ 8125.976542] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 8126.382120] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 8128.119212] Key type lgssc registered [ 8131.068956] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8210.936167] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8223.046972] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8251.776892] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8254.665521] Lustre: Mounted lustre-client [ 8260.084616] Lustre: lustre-MDT0000-mdc-ffff8bcd055fe000: Connection to lustre-MDT0000 (at 192.168.202.113@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8276.447247] Lustre: 127615:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785404753/real 1785404753] req@00000000e3b2b171 x1872132433775744/t0(0) o400->MGC192.168.202.113@tcp@192.168.202.113@tcp:26/25 lens 224/224 e 0 to 1 dl 1785404769 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8276.495933] LustreError: MGC192.168.202.113@tcp: Connection to MGS (at 192.168.202.113@tcp) was lost; in progress operations using this service will fail [ 8286.701903] Lustre: Evicted from MGS (at 192.168.202.113@tcp) after server handle changed from 0xa0d49b8d9dd5c831 to 0xa0d49b8d9dd5c885 [ 8286.725683] Lustre: MGC192.168.202.113@tcp: Connection restored to 192.168.202.113@tcp (at 192.168.202.113@tcp) [ 8293.492942] Lustre: lustre-MDT0000-mdc-ffff8bcd055fe000: Connection restored to 192.168.202.113@tcp (at 192.168.202.113@tcp) [ 8312.713663] Lustre: Unmounted lustre-client [ 8348.971152] Key type lgssc unregistered [ 8349.416202] LNet: 130615:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8349.428646] LNetError: 130615:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8349.464281] LNet: Removed LNI 192.168.202.13@tcp [ 8350.701363] Key type .llcrypt unregistered [ 8350.705972] Key type ._llcrypt unregistered [ 8373.465311] Key type ._llcrypt registered [ 8373.469672] Key type .llcrypt registered [ 8374.747296] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 05:47:46 (1785404866) [ 8377.377628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8377.441822] alg: No test for adler32 (adler32-zlib) [ 8378.905165] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 8379.468722] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 8381.359165] Key type lgssc registered [ 8383.320360] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8454.774747] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8466.180717] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8496.578534] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8499.449081] Lustre: Mounted lustre-client [ 8518.798618] Lustre: Unmounted lustre-client [ 8519.572325] Lustre: Mounted lustre-client [ 8542.429707] Lustre: Unmounted lustre-client [ 8575.346281] Key type lgssc unregistered [ 8575.972035] LNet: 134878:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8575.986987] LNetError: 134878:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8576.034828] LNet: Removed LNI 192.168.202.13@tcp [ 8577.319337] Key type .llcrypt unregistered [ 8577.326287] Key type ._llcrypt unregistered [ 8594.054147] Key type ._llcrypt registered [ 8594.055322] Key type .llcrypt registered [ 8595.766452] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 05:51:26 (1785405086) [ 8599.131635] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8599.149702] alg: No test for adler32 (adler32-zlib) [ 8600.391879] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 8600.778862] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 8602.544951] Key type lgssc registered [ 8604.283374] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8679.845990] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8691.943299] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8729.246563] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8732.821964] Lustre: Mounted lustre-client [ 8773.650610] Lustre: Unmounted lustre-client [ 8810.918207] Key type lgssc unregistered [ 8811.499279] LNet: 138198:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8811.514293] LNetError: 138198:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8812.581268] LNet: Removed LNI 192.168.202.13@tcp [ 8813.380653] Key type .llcrypt unregistered [ 8813.388755] Key type ._llcrypt unregistered [ 8842.051817] Key type ._llcrypt registered [ 8842.058377] Key type .llcrypt registered [ 8844.472986] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 05:55:34 (1785405334) [ 8847.727028] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8847.842547] alg: No test for adler32 (adler32-zlib) [ 8849.169450] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 8849.694288] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 8851.463230] Key type lgssc registered [ 8853.608931] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8933.543666] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8943.663812] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8972.477408] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8975.522246] Lustre: Mounted lustre-client [ 8979.241232] Lustre: Unmounted lustre-client [ 8979.711943] Lustre: Mounted lustre-client [ 8989.880341] Lustre: Unmounted lustre-client [ 9038.422503] Key type lgssc unregistered [ 9038.761893] LNet: 142259:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9038.781497] LNetError: 142259:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9038.805141] LNet: Removed LNI 192.168.202.13@tcp [ 9039.773148] Key type .llcrypt unregistered [ 9039.778817] Key type ._llcrypt unregistered [ 9057.974457] Key type ._llcrypt registered [ 9057.976929] Key type .llcrypt registered [ 9060.041961] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 05:59:10 (1785405550) [ 9064.025988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9064.132135] alg: No test for adler32 (adler32-zlib) [ 9065.528688] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 9065.942354] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 9067.703124] Key type lgssc registered [ 9069.240346] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9152.805640] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9160.577084] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9191.746114] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9193.390564] Lustre: Mounted lustre-client [ 9197.972748] Lustre: Unmounted lustre-client [ 9198.701176] Lustre: Mounted lustre-client [ 9219.416104] Lustre: Unmounted lustre-client [ 9265.338181] Key type lgssc unregistered [ 9265.794541] LNet: 145903:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9265.807879] LNetError: 145903:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9266.863129] LNet: Removed LNI 192.168.202.13@tcp [ 9267.938397] Key type .llcrypt unregistered [ 9267.945565] Key type ._llcrypt unregistered [ 9285.925121] Key type ._llcrypt registered [ 9285.931781] Key type .llcrypt registered [ 9288.346279] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 06:02:58 (1785405778) [ 9292.462696] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9292.725092] alg: No test for adler32 (adler32-zlib) [ 9294.315528] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 9294.832439] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 9296.760917] Key type lgssc registered [ 9299.353576] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9372.743921] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9380.897472] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9423.635801] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9425.994869] Lustre: Mounted lustre-client [ 9455.205115] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9456.579492] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 9479.249383] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 9487.689262] Lustre: Unmounted lustre-client [ 9488.011350] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 9488.103969] Lustre: Mounted lustre-client [ 9501.356419] Lustre: Unmounted lustre-client [ 9553.335249] Key type lgssc unregistered [ 9553.594829] LNet: 149975:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9553.604593] LNetError: 149975:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9554.668056] LNet: Removed LNI 192.168.202.13@tcp [ 9555.904828] Key type .llcrypt unregistered [ 9555.907770] Key type ._llcrypt unregistered [ 9599.770751] Key type ._llcrypt registered [ 9599.775552] Key type .llcrypt registered [ 9601.468701] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 06:08:12 (1785406092) [ 9605.607681] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9605.623671] alg: No test for adler32 (adler32-zlib) [ 9606.895410] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [ 9607.284455] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [ 9609.049347] Key type lgssc registered [ 9610.597444] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9690.124361] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9697.360382] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9731.819818] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9733.889520] Lustre: Mounted lustre-client [ 9912.357992] Lustre: Unmounted lustre-client [ 9913.682575] Lustre: Mounted lustre-client [ 9922.877224] Lustre: Unmounted lustre-client [ 9923.739912] Lustre: Mounted lustre-client [ 9928.201124] Lustre: Unmounted lustre-client [ 9969.915020] Key type lgssc unregistered [ 9970.116656] LNet: 155184:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9970.140681] LNetError: 155184:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9970.166531] LNet: Removed LNI 192.168.202.13@tcp [ 9972.206761] Key type .llcrypt unregistered [ 9972.209268] Key type ._llcrypt unregistered [ 9998.586432] Key type ._llcrypt registered [ 9998.589859] Key type .llcrypt registered [10000.443594] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 06:14:51 (1785406491) [10004.092149] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10004.232596] alg: No test for adler32 (adler32-zlib) [10005.436107] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [10005.763889] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [10007.487164] Key type lgssc registered [10009.214514] Lustre: Echo OBD driver; http://www.lustre.org/ [10090.775474] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10102.979651] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10131.327498] Lustre: DEBUG MARKER: oleg213-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10134.245246] Lustre: Mounted lustre-client [10141.652419] Lustre: Unmounted lustre-client [10142.445950] Lustre: Mounted lustre-client [10144.758850] Lustre: Unmounted lustre-client [10178.109150] Key type lgssc unregistered [10178.786771] LNet: 158879:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10178.799802] LNetError: 158879:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10178.830718] LNet: Removed LNI 192.168.202.13@tcp [10181.271702] Key type .llcrypt unregistered [10181.274174] Key type ._llcrypt unregistered [10211.571813] Key type ._llcrypt registered [10211.576601] Key type .llcrypt registered [10213.870375] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 06:18:24 (1785406704) [10215.327221] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10215.359551] alg: No test for adler32 (adler32-zlib) [10216.573315] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [10217.006685] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [10218.823756] Key type lgssc registered [10220.990728] Lustre: Echo OBD driver; http://www.lustre.org/ [10256.159875] LustreError: 160578:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [10261.983849] LustreError: 160587:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [10308.191420] LNet: 1 local NIs in recovery (showing 1): 192.168.202.13@tcp [10380.895187] LNet: 1 local NIs in recovery (showing 1): 192.168.202.13@tcp [10416.095577] 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 [10416.129765] Lustre: Unmounted lustre-client [10416.163688] LustreError: 160578:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -110 [10435.476517] Key type lgssc unregistered [10437.087622] LNet: 160998:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10437.111965] LNetError: 160998:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10512.041204] LNet: Removed LNI 192.168.202.13@tcp [10513.288621] Key type .llcrypt unregistered [10513.296606] Key type ._llcrypt unregistered [10574.821160] Key type ._llcrypt registered [10574.833058] Key type .llcrypt registered [10575.577611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10575.630920] alg: No test for adler32 (adler32-zlib) [10577.798317] Lustre: Lustre: Build Version: 2.17.56_2_gaec046b [10578.442596] LNet: Added LNI 192.168.202.13@tcp [8/256/0/180] [10580.176464] Key type lgssc registered [10584.872258] Lustre: Echo OBD driver; http://www.lustre.org/ [10656.890246] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 10213 sec ========= 06:25:47 (1785407147) [10659.198310] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 06:25:50 (1785407150) === [10663.715509] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 06:25:54 (1785407154) === [10716.472563] Key type lgssc unregistered [10716.922702] LNet: 165247:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10716.936867] LNetError: 165247:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10716.969436] LNet: Removed LNI 192.168.202.13@tcp [10718.347996] Key type .llcrypt unregistered [10718.350248] Key type ._llcrypt unregistered