[ 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 485661801 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 2592MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.003300] x2apic enabled [ 0.004007] Switched APIC routing to physical x2apic. [ 0.005012] kvm-guest: setup PV IPIs [ 0.008617] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010009] pid_max: default: 32768 minimum: 301 [ 0.011321] LSM: Security Framework initializing [ 0.012040] Yama: becoming mindful. [ 0.013030] SELinux: Initializing. [ 0.014056] *** VALIDATE selinux *** [ 0.023280] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027804] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028215] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029108] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030109] *** VALIDATE tmpfs *** [ 0.032093] *** VALIDATE proc *** [ 0.033270] *** VALIDATE cgroup *** [ 0.034010] *** VALIDATE cgroup2 *** [ 0.035291] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036149] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038084] Spectre V2 : User space: Vulnerable [ 0.039009] Speculative Store Bypass: Vulnerable [ 0.043000] debug: unmapping init [mem 0xffffffff90c59000-0xffffffff90c60fff] [ 0.045232] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047285] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048025] ... version: 2 [ 0.049014] ... bit width: 48 [ 0.050011] ... generic registers: 4 [ 0.051012] ... value mask: 0000ffffffffffff [ 0.052015] ... max period: 00007fffffffffff [ 0.053015] ... fixed-purpose events: 3 [ 0.054012] ... event mask: 000000070000000f [ 0.055293] rcu: Hierarchical SRCU implementation. [ 0.057910] smp: Bringing up secondary CPUs ... [ 0.058983] x86: Booting SMP configuration: [ 0.059030] .... node #0, CPUs: #1 #2 #3 [ 0.066163] smp: Brought up 1 node, 4 CPUs [ 0.068011] smpboot: Max logical packages: 1 [ 0.069019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.113027] node 0 deferred pages initialised in 41ms [ 0.118008] devtmpfs: initialized [ 0.119348] x86/mm: Memory block size: 128MB [ 0.124408] gcov: version magic: 0x41383552 [ 0.128033] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.132178] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.135380] pinctrl core: initialized pinctrl subsystem [ 0.138213] [ 0.138944] ************************************************************* [ 0.142013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.145011] ** ** [ 0.148012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.150010] ** ** [ 0.153011] ** This means that this kernel is built to expose internal ** [ 0.156013] ** IOMMU data structures, which may compromise security on ** [ 0.159011] ** your system. ** [ 0.162011] ** ** [ 0.165012] ** If you see this message and you are not debugging the ** [ 0.168010] ** kernel, report this immediately to your vendor! ** [ 0.171013] ** ** [ 0.173008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.176063] ************************************************************* [ 0.179871] NET: Registered protocol family 16 [ 0.182415] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.184068] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.185000] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.185121] cpuidle: using governor menu [ 0.187000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.191715] PCI: Using configuration type 1 for base access [ 0.193270] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.202394] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.203015] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.206193] cryptd: max_cpu_qlen set to 1000 [ 0.209032] ACPI: Added _OSI(Module Device) [ 0.210129] ACPI: Added _OSI(Processor Device) [ 0.212015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.214012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.219758] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.225145] ACPI: Interpreter enabled [ 0.227141] ACPI: PM: (supports S0 S3 S4 S5) [ 0.228009] ACPI: Using IOAPIC for interrupt routing [ 0.230092] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.234443] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.245597] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.248038] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.250016] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.253092] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.256593] acpiphp: Slot [2] registered [ 0.258169] acpiphp: Slot [5] registered [ 0.260112] acpiphp: Slot [6] registered [ 0.261154] acpiphp: Slot [3] registered [ 0.263121] acpiphp: Slot [4] registered [ 0.265098] acpiphp: Slot [7] registered [ 0.266159] acpiphp: Slot [8] registered [ 0.268064] acpiphp: Slot [9] registered [ 0.269095] acpiphp: Slot [10] registered [ 0.271095] acpiphp: Slot [11] registered [ 0.272154] acpiphp: Slot [12] registered [ 0.274063] acpiphp: Slot [13] registered [ 0.275066] acpiphp: Slot [14] registered [ 0.276082] acpiphp: Slot [15] registered [ 0.278096] acpiphp: Slot [16] registered [ 0.280102] acpiphp: Slot [17] registered [ 0.281082] acpiphp: Slot [18] registered [ 0.283118] acpiphp: Slot [19] registered [ 0.285101] acpiphp: Slot [20] registered [ 0.287129] acpiphp: Slot [21] registered [ 0.288083] acpiphp: Slot [22] registered [ 0.289030] acpiphp: Slot [23] registered [ 0.290066] acpiphp: Slot [24] registered [ 0.291185] acpiphp: Slot [25] registered [ 0.292050] acpiphp: Slot [26] registered [ 0.294070] acpiphp: Slot [27] registered [ 0.295142] acpiphp: Slot [28] registered [ 0.296101] acpiphp: Slot [29] registered [ 0.298094] acpiphp: Slot [30] registered [ 0.299083] acpiphp: Slot [31] registered [ 0.301082] PCI host bridge to bus 0000:00 [ 0.303021] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.305114] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.308023] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.311022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.314024] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.318027] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.320168] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.324235] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.326510] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.335013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.339057] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.342021] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.345020] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.348017] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.350684] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.353927] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.356043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.359731] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.363012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.372012] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.373000] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.377177] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.389017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.400019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.437019] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.454260] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.470014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.488017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.517014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.537529] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.541415] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.543374] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.545330] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.548276] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.552186] iommu: Default domain type: Passthrough [ 0.554338] SCSI subsystem initialized [ 0.555096] ACPI: bus type USB registered [ 0.557103] usbcore: registered new interface driver usbfs [ 0.559062] usbcore: registered new interface driver hub [ 0.561125] usbcore: registered new device driver usb [ 0.563431] pps_core: LinuxPPS API ver. 1 registered [ 0.565010] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.568055] PTP clock support registered [ 0.570168] EDAC MC: Ver: 3.0.0 [ 0.571664] PCI: Using ACPI for IRQ routing [ 0.572000] NetLabel: Initializing [ 0.572000] NetLabel: domain hash size = 128 [ 0.574013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.576072] NetLabel: unlabeled traffic allowed by default [ 0.578273] vgaarb: loaded [ 0.579416] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.582016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.588000] clocksource: Switched to clocksource kvm-clock [ 0.721612] VFS: Disk quotas dquot_6.6.0 [ 0.723907] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.726447] *** VALIDATE ramfs *** [ 0.727748] *** VALIDATE hugetlbfs *** [ 0.729133] pnp: PnP ACPI init [ 0.731593] pnp: PnP ACPI: found 6 devices [ 0.747550] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.750727] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.752811] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.754867] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.757213] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.759498] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.762976] NET: Registered protocol family 2 [ 0.765359] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.770223] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.773961] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.779187] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.782702] TCP: Hash tables configured (established 65536 bind 65536) [ 0.785597] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.789097] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.792305] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.795589] NET: Registered protocol family 1 [ 0.798211] RPC: Registered named UNIX socket transport module. [ 0.802708] RPC: Registered udp transport module. [ 0.804434] RPC: Registered tcp transport module. [ 0.806171] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.808419] NET: Registered protocol family 44 [ 0.809767] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.813355] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.816733] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.820045] PCI: CLS 0 bytes, default 64 [ 0.823196] Unpacking initramfs... [ 2.404538] debug: unmapping init [mem 0xffff98887cc64000-0xffff98887ffcffff] [ 2.408731] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.412260] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.415544] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.968058] Initialise system trusted keyrings [ 2.969727] Key type blacklist registered [ 2.971477] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.981121] zbud: loaded [ 2.984049] *** VALIDATE nfs *** [ 2.985658] *** VALIDATE nfs4 *** [ 2.987182] pstore: using deflate compression [ 2.990881] Platform Keyring initialized [ 3.101764] NET: Registered protocol family 38 [ 3.103400] Key type asymmetric registered [ 3.104570] Asymmetric key parser 'x509' registered [ 3.106200] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.109093] io scheduler mq-deadline registered [ 3.110924] io scheduler kyber registered [ 3.112822] io scheduler bfq registered [ 3.188330] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.193168] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.195715] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.198187] ACPI: Power Button [PWRF] [ 3.203385] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.209401] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.261559] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.291834] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.320685] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.325415] Non-volatile memory driver v1.3 [ 3.326990] Linux agpgart interface v0.103 [ 3.356428] virtio_blk virtio1: [vda] 146536 512-byte logical blocks (75.0 MB/71.6 MiB) [ 3.358622] vda: detected capacity change from 0 to 75026432 [ 3.376347] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.378556] vdb: detected capacity change from 0 to 1073741824 [ 3.393342] libphy: Fixed MDIO Bus: probed [ 3.409368] usbcore: registered new interface driver usbserial_generic [ 3.411554] usbserial: USB Serial support registered for generic [ 3.413475] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.417053] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.418665] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.420898] mousedev: PS/2 mouse device common for all mice [ 3.426374] rtc_cmos 00:05: RTC can wake from S4 [ 3.432334] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.437380] rtc_cmos 00:05: registered as rtc0 [ 3.440456] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.444831] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.446015] intel_pstate: CPU model not supported [ 3.453254] hid: raw HID events driver (C) Jiri Kosina [ 3.456583] usbcore: registered new interface driver usbhid [ 3.458355] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.461610] usbhid: USB HID core driver [ 3.468568] drop_monitor: Initializing network drop monitor service [ 3.470565] Initializing XFRM netlink socket [ 3.472471] NET: Registered protocol family 10 [ 3.475223] Segment Routing with IPv6 [ 3.476543] NET: Registered protocol family 17 [ 3.478360] mpls_gso: MPLS GSO support [ 3.483982] RAS: Correctable Errors collector initialized. [ 3.485793] AVX version of gcm_enc/dec engaged. [ 3.487189] AES CTR mode by8 optimization enabled [ 3.555372] sched_clock: Marking stable (3555297840, 0)->(4677101064, -1121803224) [ 3.558877] registered taskstats version 1 [ 3.560832] Loading compiled-in X.509 certificates [ 3.563314] zswap: loaded using pool lzo/zbud [ 3.586815] Key type big_key registered [ 3.599704] Key type encrypted registered [ 3.601114] ima: No TPM chip found, activating TPM-bypass! [ 3.602861] ima: Allocated hash algorithm: sha1 [ 3.604383] ima: No architecture policies found [ 3.605752] evm: Initialising EVM extended attributes: [ 3.609685] evm: security.selinux [ 3.610730] evm: security.ima [ 3.611693] evm: security.capability [ 3.612770] evm: HMAC attrs: 0x1 [ 3.614794] rtc_cmos 00:05: setting system clock to 2026-08-22 04:27:54 UTC (1787372874) [ 3.620421] debug: unmapping init [mem 0xffffffff91c03000-0xffffffff91dfffff] [ 3.622812] debug: unmapping init [mem 0xffffffff90982000-0xffffffff90c58fff] [ 3.631049] Write protecting the kernel read-only data: 28672k [ 3.634457] debug: unmapping init [mem 0xffffffff8f003000-0xffffffff8f1fffff] [ 3.636802] debug: unmapping init [mem 0xffffffff8f914000-0xffffffff8f9fffff] [ 3.667550] 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.674741] systemd[1]: Detected virtualization kvm. [ 3.678782] systemd[1]: Detected architecture x86-64. [ 3.680559] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.710294] systemd[1]: No hostname configured. [ 3.712012] systemd[1]: Set hostname to . [ 3.714034] random: systemd: uninitialized urandom read (16 bytes read) [ 3.716397] systemd[1]: Initializing machine ID from random generator. [ 3.827246] random: ln: uninitialized urandom read (6 bytes read) [ 3.961492] random: systemd: uninitialized urandom read (16 bytes read) [ 3.964493] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.978547] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.985989] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Swap. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.248983] device-mapper: uevent: version 1.0.3 [ 5.251148] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 6.462209] virtio_net virtio0 ens2: renamed from eth0 [ 7.086685] scsi host0: ata_piix [ 7.202038] scsi host1: ata_piix [ 7.204260] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.206237] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.139496] random: crng init done [ 12.146949] random: 7 urandom warning(s) missed due to ratelimiting [ 13.770877] dracut-initqueue[584]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 16.799293] 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. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 24.108606] printk: systemd: 26 output lines suppressed due to ratelimiting [ 27.595495] SELinux: Disabled at runtime. [ 27.866493] 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) [ 27.883343] systemd[1]: Detected virtualization kvm. [ 27.886563] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 29.773964] systemd[1]: initrd-switch-root.service: Succeeded. [ 29.781861] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 29.793925] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 29.803555] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 29.813993] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 29.851309] systemd[1]: Starting Journal Service... Starting Journal Service... [ 29.911917] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-getty.slice. Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Remount Root and Kernel File Systems... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on initctl Compatibility Named Pipe. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice User and Session Slice. [ 32.656716] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [ 32.923697] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 32.961524] systemd[1]: Reached target RPC Port Mapper. [ OK ] Reached target RPC Port Mapper. [ 33.032355] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 33.083627] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 33.104315] systemd[1]: Reached target Local Encrypted Volumes. [ OK ] Reached target Local Encrypted Volumes. [ 33.169379] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 33.259733] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... [ 33.325466] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. [ 33.366056] systemd[1]: sysroot-var-lib-nfs-rpc_pipefs.mount: Succeeded. [ 33.385527] systemd[1]: sysroot.mount: Succeeded. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 34.703278] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 37.528639] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 37.754426] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 41.100525] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [* ] A start job is running for Configur…only root support (11s / no limit)[ 41.403779] EDAC sbridge: Ver: 1.1.2 [** ] A start job is running for Configur…only root support (12s / no limit) [*** ] A start job is running for Configur…only root support (12s / no limit) [ *** ] A start job is running for Configur…only root support (13s / no limit) [ *** ] A start job is running for Configur…only root support (13s / no limit) [ ***] A start job is running for Configur…only root support (13s / no limit) [ **] A start job is running for Configur…only root support (14s / no limit) [ *] A start job is running for Configur…only root support (14s / no limit) [ **] A start job is running for Configur…only root support (15s / no limit) [ ***] A start job is running for Configur…only root support (15s / no limit) [ *** ] A start job is running for Configur…only root support (16s / no limit) [ *** ] A start job is running for Configur…only root support (16s / no limit) [*** ] A start job is running for Configur…only root support (17s / no limit)[ 47.227311] Key type dns_resolver registered [** ] A start job is running for Configur…only root support (18s / no limit)[ 48.057477] NFS: Registering the id_resolver key type [ 48.060297] Key type id_resolver registered [ 48.062393] Key type id_legacy registered [* ] A start job is running for Configur…only root support (18s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ 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 update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Login Service. [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ 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 oleg103-client login: [ 75.539273] hrtimer: interrupt took 3265882 ns [ 112.831570] libcfs: loading out-of-tree module taints kernel. [ 112.933088] Key type ._llcrypt registered [ 112.935272] Key type .llcrypt registered [ 113.602831] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 113.611087] alg: No test for adler32 (adler32-zlib) [ 115.079431] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 115.863537] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 117.711283] Key type lgssc registered [ 119.620704] Lustre: Echo OBD driver; http://www.lustre.org/ [ 284.975870] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 292.102866] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 306.211407] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing check_logdir /tmp/testlogs/ [ 310.755800] Lustre: lustre-OST0000-osc-ffff9888c79b4000: disconnect after 23s idle [ 311.155408] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing yml_node [ 315.574601] Lustre: DEBUG MARKER: Client: 2.17.57.45 [ 318.011141] Lustre: DEBUG MARKER: MDS: 2.17.57.45 [ 320.701903] Lustre: DEBUG MARKER: OSS: 2.17.57.45 [ 322.491532] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sat Aug 22 00:33:11 EDT 2026 [ 339.488368] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 340.621519] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 343.773981] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 354.117942] Lustre: Unmounted lustre-client [ 474.365404] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 479.978378] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 503.188082] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 546.051377] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 00:36:55 (1787373415) [ 579.071399] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 584.853509] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 605.457699] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 605.935831] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 610.140792] Lustre: Unmounted lustre-client [ 637.999833] Key type lgssc unregistered [ 638.239718] LNet: 14675:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 638.246978] LNetError: 14675:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 638.261860] LNet: Removed LNI 192.168.201.3@tcp [ 639.145375] Key type .llcrypt unregistered [ 639.148607] Key type ._llcrypt unregistered [ 658.411495] Key type ._llcrypt registered [ 658.413195] Key type .llcrypt registered [ 659.949715] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 00:38:49 (1787373529) [ 662.844890] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 662.869246] alg: No test for adler32 (adler32-zlib) [ 663.943850] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 664.204275] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 665.943261] Key type lgssc registered [ 667.572707] Lustre: Echo OBD driver; http://www.lustre.org/ [ 719.313690] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 724.970711] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 745.620448] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 756.355421] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 759.192103] Lustre: Unmounted lustre-client [ 791.171604] Key type lgssc unregistered [ 791.450320] LNet: 18357:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 791.465921] LNetError: 18357:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 792.497188] LNet: Removed LNI 192.168.201.3@tcp [ 793.534744] Key type .llcrypt unregistered [ 793.538510] Key type ._llcrypt unregistered [ 809.092646] Key type ._llcrypt registered [ 809.095762] Key type .llcrypt registered [ 810.443522] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 00:41:19 (1787373679) [ 812.782432] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 812.796862] alg: No test for adler32 (adler32-zlib) [ 813.835780] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 814.071548] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 815.719675] Key type lgssc registered [ 816.808213] Lustre: Echo OBD driver; http://www.lustre.org/ [ 869.062348] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 874.527376] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 902.238252] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 904.997061] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 907.339573] Lustre: Unmounted lustre-client [ 933.496015] Key type lgssc unregistered [ 933.713750] LNet: 22020:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 933.719146] LNetError: 22020:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 933.732938] LNet: Removed LNI 192.168.201.3@tcp [ 934.285891] Key type .llcrypt unregistered [ 934.288210] Key type ._llcrypt unregistered [ 948.333359] Key type ._llcrypt registered [ 948.336273] Key type .llcrypt registered [ 949.453855] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 00:43:39 (1787373819) [ 951.526364] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 951.615437] alg: No test for adler32 (adler32-zlib) [ 952.654307] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 952.891664] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 954.591256] Key type lgssc registered [ 955.829682] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1003.213549] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1008.998188] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1030.253411] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1031.855383] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 1039.222309] Lustre: Unmounted lustre-client [ 1067.383840] Key type lgssc unregistered [ 1067.608710] LNet: 25635:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1067.628520] LNetError: 25635:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1067.652824] LNet: Removed LNI 192.168.201.3@tcp [ 1068.493243] Key type .llcrypt unregistered [ 1068.498345] Key type ._llcrypt unregistered [ 1084.974520] Key type ._llcrypt registered [ 1084.976822] Key type .llcrypt registered [ 1086.163864] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 00:45:55 (1787373955) [ 1088.460405] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1088.513905] alg: No test for adler32 (adler32-zlib) [ 1089.689407] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 1089.970956] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1091.673645] Key type lgssc registered [ 1092.871325] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1138.358411] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1143.361179] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1161.067426] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1163.638985] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 1168.893453] Lustre: lustre-OST0000-osc-ffff9888c6c8f800: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1176.827711] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1187.202266] Lustre: Unmounted lustre-client [ 1221.980355] Key type lgssc unregistered [ 1222.236842] LNet: 29251:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1222.251407] LNetError: 29251:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1222.272661] LNet: Removed LNI 192.168.201.3@tcp [ 1222.889577] Key type .llcrypt unregistered [ 1222.890840] Key type ._llcrypt unregistered [ 1237.324923] Key type ._llcrypt registered [ 1237.327641] Key type .llcrypt registered [ 1239.079706] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 00:48:27 (1787374107) [ 1241.440732] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1241.549416] alg: No test for adler32 (adler32-zlib) [ 1242.655704] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 1242.921828] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1244.719714] Key type lgssc registered [ 1245.758442] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1288.776541] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1293.389600] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1310.816738] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1316.909272] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 1321.960785] Lustre: lustre-MDT0000-mdc-ffff9888c3fe1000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1332.199587] Lustre: lustre-MDT0001-mdc-ffff9888c3fe1000: Connection to lustre-MDT0001 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1338.290393] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1342.432839] Lustre: 30210:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787374197/real 1787374197] req@000000000c16c59f x1874197597918976/t0(0) o400->MGC192.168.201.103@tcp@192.168.201.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1787374213 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1342.453473] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 1348.694196] Lustre: Unmounted lustre-client [ 1367.823713] Key type lgssc unregistered [ 1368.139425] LNet: 32901:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1368.145849] LNetError: 32901:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1368.172200] LNet: Removed LNI 192.168.201.3@tcp [ 1368.947970] Key type .llcrypt unregistered [ 1368.951482] Key type ._llcrypt unregistered [ 1383.866821] Key type ._llcrypt registered [ 1383.870435] Key type .llcrypt registered [ 1385.075629] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 00:50:54 (1787374254) [ 1387.203212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1387.219742] alg: No test for adler32 (adler32-zlib) [ 1388.327738] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 1388.491502] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1390.208080] Key type lgssc registered [ 1391.300841] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1471.290321] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1477.088757] LustreError: 35101:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.103@tcp: failed processing log, type 1: rc = -110 [ 1484.258479] LustreError: 35110:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1634.277365] LustreError: MGC192.168.201.103@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 1634.300779] Lustre: Unmounted lustre-client [ 1634.312612] LustreError: 35101:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1643.882272] Key type lgssc unregistered [ 1644.081395] LNet: 35529:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1644.090917] LNetError: 35529:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1645.157316] LNet: Removed LNI 192.168.201.3@tcp [ 1645.828876] Key type .llcrypt unregistered [ 1645.831390] Key type ._llcrypt unregistered [ 1659.264260] Key type ._llcrypt registered [ 1659.266873] Key type .llcrypt registered [ 1660.350356] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 00:55:30 (1787374530) [ 1662.265790] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1662.476221] alg: No test for adler32 (adler32-zlib) [ 1663.648347] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 1663.957030] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1665.775142] Key type lgssc registered [ 1666.792316] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1709.948413] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1715.449299] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1736.508584] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1736.988675] LustreError: 38620:0:(llite_lib.c:1555:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1737.013107] Lustre: Unmounted wrong.lustre-client [ 1737.022860] LustreError: 38620:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1773.440800] Key type lgssc unregistered [ 1773.813696] LNet: 39041:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1773.827416] LNetError: 39041:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1773.845951] LNet: Removed LNI 192.168.201.3@tcp [ 1774.509946] Key type .llcrypt unregistered [ 1774.512820] Key type ._llcrypt unregistered [ 1791.230536] Key type ._llcrypt registered [ 1791.233812] Key type .llcrypt registered [ 1792.764676] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 00:57:41 (1787374661) [ 1795.955203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1795.999690] alg: No test for adler32 (adler32-zlib) [ 1796.960062] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 1797.159098] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1798.791210] Key type lgssc registered [ 1799.563369] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1887.442162] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1924.100922] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1930.761528] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1942.776743] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 1945.145399] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1955.531536] Lustre: Unmounted lustre-client [ 1983.798530] Key type lgssc unregistered [ 1984.142047] LNet: 42620:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1984.164579] LNetError: 42620:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1984.196223] LNet: Removed LNI 192.168.201.3@tcp [ 1985.114730] Key type .llcrypt unregistered [ 1985.118550] Key type ._llcrypt unregistered [ 2005.834270] Key type ._llcrypt registered [ 2005.836390] Key type .llcrypt registered [ 2007.451731] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 01:01:16 (1787374876) [ 2010.136222] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2010.178870] alg: No test for adler32 (adler32-zlib) [ 2011.409415] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 2011.762255] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2013.471211] Key type lgssc registered [ 2014.990091] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2069.236684] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2074.529724] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2094.173815] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2094.762896] LustreError: 43592:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2099.871887] LustreError: 45715:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.103@tcp: failed processing log, type 1: rc = -110 [ 2104.780932] LustreError: 43592:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2106.070125] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 2108.030897] Lustre: Unmounted lustre-client [ 2144.868384] Key type lgssc unregistered [ 2145.218651] LNet: 46177:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2145.227545] LNetError: 46177:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2145.248378] LNet: Removed LNI 192.168.201.3@tcp [ 2146.283066] Key type .llcrypt unregistered [ 2146.286420] Key type ._llcrypt unregistered [ 2161.566790] Key type ._llcrypt registered [ 2161.568730] Key type .llcrypt registered [ 2162.697948] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 01:03:52 (1787375032) [ 2163.956356] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2165.818738] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 01:03:55 (1787375035) [ 2167.091201] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2167.279603] alg: No test for adler32 (adler32-zlib) [ 2168.366565] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 2168.643887] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2170.255193] Key type lgssc registered [ 2171.306873] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2194.330844] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 01:04:24 (1787375064) [ 2226.243539] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2231.956331] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2253.630818] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2256.342678] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 2261.490814] Lustre: lustre-MDT0000-mdc-ffff9888c6c8b000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2271.724222] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 2281.964779] Lustre: Evicted from MGS (at 192.168.201.103@tcp) after server handle changed from 0x840c8b74a47cf6dd to 0x840c8b74a47cf95a [ 2281.991221] Lustre: MGC192.168.201.103@tcp: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 2287.144587] Lustre: lustre-MDT0000-mdc-ffff9888c6c8b000: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 2295.374386] Lustre: Unmounted lustre-client [ 2324.071359] Key type lgssc unregistered [ 2324.389177] LNet: 51038:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2324.398326] LNetError: 51038:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2324.415876] LNet: Removed LNI 192.168.201.3@tcp [ 2325.104896] Key type .llcrypt unregistered [ 2325.107694] Key type ._llcrypt unregistered [ 2342.128350] Key type ._llcrypt registered [ 2342.132315] Key type .llcrypt registered [ 2343.456457] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 01:06:52 (1787375212) [ 2346.843495] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2346.859676] alg: No test for adler32 (adler32-zlib) [ 2347.887771] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 2348.155678] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2349.831388] Key type lgssc registered [ 2351.047492] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2403.261617] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2409.632289] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2433.189473] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2435.965236] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 2441.208876] Lustre: lustre-MDT0000-mdc-ffff9888c38a6800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2457.503131] Lustre: 52001:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787375312/real 1787375312] req@000000009e3a55c4 x1874198757641856/t0(0) o400->MGC192.168.201.103@tcp@192.168.201.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1787375328 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2457.515259] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 2466.362083] Lustre: lustre-MDT0000-mdc-ffff9888c38a6800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 2467.369806] Lustre: Evicted from MGS (at 192.168.201.103@tcp) after server handle changed from 0x627e411884a81048 to 0x627e411884a813f2 [ 2467.378235] Lustre: MGC192.168.201.103@tcp: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 2471.464735] Lustre: Unmounted lustre-client [ 2515.975195] Key type lgssc unregistered [ 2516.245844] LNet: 55007:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2516.257463] LNetError: 55007:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2516.273118] LNet: Removed LNI 192.168.201.3@tcp [ 2516.991741] Key type .llcrypt unregistered [ 2516.994838] Key type ._llcrypt unregistered [ 2532.993099] Key type ._llcrypt registered [ 2532.996144] Key type .llcrypt registered [ 2534.164418] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 01:10:03 (1787375403) [ 2534.681181] Key type .llcrypt unregistered [ 2534.683440] Key type ._llcrypt unregistered [ 2541.332401] Key type ._llcrypt registered [ 2541.334824] Key type .llcrypt registered [ 2541.401592] Key type .llcrypt unregistered [ 2541.405703] Key type ._llcrypt unregistered [ 2546.521514] Key type ._llcrypt registered [ 2546.523675] Key type .llcrypt registered [ 2548.173874] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 01:10:17 (1787375417) [ 2550.748462] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2550.764496] alg: No test for adler32 (adler32-zlib) [ 2551.780597] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 2551.976041] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2553.599524] Key type lgssc registered [ 2555.069560] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2601.225321] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2606.345430] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2626.498993] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2627.991571] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 2630.260686] Lustre: Unmounted lustre-client [ 2630.536953] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 2633.777720] Lustre: Unmounted lustre-client [ 2659.231685] Key type lgssc unregistered [ 2659.558903] LNet: 59432:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2659.567653] LNetError: 59432:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2659.594163] LNet: Removed LNI 192.168.201.3@tcp [ 2660.442730] Key type .llcrypt unregistered [ 2660.444186] Key type ._llcrypt unregistered [ 2675.133403] Key type ._llcrypt registered [ 2675.135502] Key type .llcrypt registered [ 2676.398190] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 01:12:25 (1787375545) [ 2678.235848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2678.351964] alg: No test for adler32 (adler32-zlib) [ 2679.445642] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 2679.683140] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2681.367365] Key type lgssc registered [ 2682.490733] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2734.289249] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2739.999487] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2758.891055] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2761.507251] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 2763.569699] Lustre: Unmounted lustre-client [ 2789.277842] Key type lgssc unregistered [ 2789.435324] LNet: 63000:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2789.441431] LNetError: 63000:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2789.464258] LNet: Removed LNI 192.168.201.3@tcp [ 2790.036635] Key type .llcrypt unregistered [ 2790.039501] Key type ._llcrypt unregistered [ 2802.254358] Key type ._llcrypt registered [ 2802.256265] Key type .llcrypt registered [ 2803.375381] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 01:14:33 (1787375673) [ 2805.913149] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2805.939367] alg: No test for adler32 (adler32-zlib) [ 2806.977329] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 2807.167404] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2808.825104] Key type lgssc registered [ 2810.090201] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2855.949809] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2861.171622] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2881.899273] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2884.843116] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 2887.740814] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 2891.178634] Lustre: Unmounted lustre-client [ 2893.327949] Lustre: Unmounted lustre-client [ 2920.261226] Key type lgssc unregistered [ 2920.476692] LNet: 66654:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2920.486846] LNetError: 66654:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2920.503344] LNet: Removed LNI 192.168.201.3@tcp [ 2921.168053] Key type .llcrypt unregistered [ 2921.170329] Key type ._llcrypt unregistered [ 2935.664850] Key type ._llcrypt registered [ 2935.667358] Key type .llcrypt registered [ 2936.852383] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 01:16:46 (1787375806) [ 2939.252965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2939.314412] alg: No test for adler32 (adler32-zlib) [ 2940.347909] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 2940.555045] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2942.224224] Key type lgssc registered [ 2943.437357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3028.119655] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3043.482246] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 01:18:32 (1787375912) [ 3078.632733] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3085.170904] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3107.092761] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3109.872126] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 3121.571752] Lustre: Unmounted lustre-client [ 3149.667848] Key type lgssc unregistered [ 3149.976103] LNet: 71427:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3149.983491] LNetError: 71427:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3150.005939] LNet: Removed LNI 192.168.201.3@tcp [ 3150.934682] Key type .llcrypt unregistered [ 3150.939892] Key type ._llcrypt unregistered [ 3168.986849] Key type ._llcrypt registered [ 3168.992338] Key type .llcrypt registered [ 3170.532732] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 01:20:39 (1787376039) [ 3191.249773] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 01:21:00 (1787376060) [ 3193.979683] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3193.998213] alg: No test for adler32 (adler32-zlib) [ 3195.117151] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 3195.445747] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 3197.111271] Key type lgssc registered [ 3198.397051] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3249.143486] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3255.780435] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3278.764426] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3281.569233] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 3286.385617] Lustre: Unmounted lustre-client [ 3324.352444] Key type lgssc unregistered [ 3324.666942] LNet: 75721:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3324.673050] LNetError: 75721:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3324.690413] LNet: Removed LNI 192.168.201.3@tcp [ 3325.663578] Key type .llcrypt unregistered [ 3325.670152] Key type ._llcrypt unregistered [ 3343.542865] Key type ._llcrypt registered [ 3343.549172] Key type .llcrypt registered [ 3344.268658] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3344.289557] alg: No test for adler32 (adler32-zlib) [ 3345.545751] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 3345.805671] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 3347.567347] Key type lgssc registered [ 3349.302899] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3440.245888] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3565.900734] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3573.584764] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3601.565315] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3647.818761] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 01:28:36 (1787376516) [ 3651.819484] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3759.721385] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3767.460800] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3793.733716] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3873.049207] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3880.052504] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3901.683366] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3903.396039] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 3911.440360] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 3913.747957] Lustre: Unmounted lustre-client [ 3940.085859] Key type lgssc unregistered [ 3940.306087] LNet: 87419:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3940.310392] LNetError: 87419:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3940.325966] LNet: Removed LNI 192.168.201.3@tcp [ 3940.924945] Key type .llcrypt unregistered [ 3940.931659] Key type ._llcrypt unregistered [ 3970.279933] Key type ._llcrypt registered [ 3970.286174] Key type .llcrypt registered [ 3970.878956] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3970.891239] alg: No test for adler32 (adler32-zlib) [ 3972.090563] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 3972.433776] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 3974.127155] Key type lgssc registered [ 3975.251637] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4051.976055] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4057.282765] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4077.532789] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4122.767877] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 01:36:32 (1787376992) [ 4150.373257] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4155.505457] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4182.279532] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 01:37:32 (1787377052) [ 4247.920946] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4257.261554] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 01:38:46 (1787377126) [ 4286.274921] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4291.497929] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4311.577626] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4313.323810] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 4313.958452] Lustre: Remounted lustre-client read-only [ 4316.303834] Lustre: Unmounted lustre-client [ 4352.550274] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 01:40:21 (1787377221) [ 4388.544602] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4395.504745] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4418.707033] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4463.390873] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 01:42:12 (1787377332) [ 4531.548382] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4568.867402] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4575.493176] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4621.254199] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 01:44:50 (1787377490) [ 4691.462501] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4731.917134] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4738.043330] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4765.823411] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4837.660544] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 01:48:26 (1787377706) [ 4839.176704] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 4841.307225] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 4843.387400] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 01:48:32 (1787377712) [ 4876.407208] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4882.828829] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4907.096233] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4929.239965] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 4931.469304] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4941.792338] Lustre: Unmounted lustre-client [ 4965.842527] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4966.879094] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 4985.360445] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9888d817f000.ost_server_uuid 50 [ 4987.122620] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9888d817f000.ost_server_uuid in FULL state after 0 sec [ 4990.144231] Lustre: Unmounted lustre-client [ 5023.543174] Key type lgssc unregistered [ 5023.904091] LNet: 107788:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5023.910644] LNetError: 107788:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5023.930158] LNet: Removed LNI 192.168.201.3@tcp [ 5024.713925] Key type .llcrypt unregistered [ 5024.716226] Key type ._llcrypt unregistered [ 5040.772755] Key type ._llcrypt registered [ 5040.775495] Key type .llcrypt registered [ 5041.995800] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 01:51:51 (1787377911) [ 5043.908951] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5043.999943] alg: No test for adler32 (adler32-zlib) [ 5045.270678] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 5045.528518] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5047.231192] Key type lgssc registered [ 5048.278151] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5100.343622] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5105.759351] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5130.646118] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5132.603717] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 5137.906225] Lustre: lustre-MDT0000-mdc-ffff9888c6796800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5138.878851] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5149.197584] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 5149.254262] Lustre: Unmounted lustre-client [ 5172.734250] LustreError: lustre-MDT0000-mdc-ffff9888d0a6a000: operation mds_connect to node 192.168.201.103@tcp failed: rc = -16 [ 5177.811960] LustreError: 111175:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9888d0a6a000: can't stat MDS #0: rc = -16 [ 5177.922548] Lustre: Unmounted lustre-client [ 5177.934078] LustreError: 111175:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 5204.358555] Key type lgssc unregistered [ 5204.750627] LNet: 111634:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5204.760650] LNetError: 111634:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5204.777227] LNet: Removed LNI 192.168.201.3@tcp [ 5205.668699] Key type .llcrypt unregistered [ 5205.671700] Key type ._llcrypt unregistered [ 5222.361286] Key type ._llcrypt registered [ 5222.365950] Key type .llcrypt registered [ 5223.653643] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 01:54:53 (1787378093) [ 5226.197456] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5226.261551] alg: No test for adler32 (adler32-zlib) [ 5227.428855] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 5227.785522] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5229.543219] Key type lgssc registered [ 5230.820194] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5282.877552] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5289.438560] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5312.261381] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5315.054050] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 5317.248748] Lustre: Unmounted lustre-client [ 5356.719700] Key type lgssc unregistered [ 5357.056078] LNet: 115167:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5357.064327] LNetError: 115167:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5358.134397] LNet: Removed LNI 192.168.201.3@tcp [ 5358.776686] Key type .llcrypt unregistered [ 5358.779037] Key type ._llcrypt unregistered [ 5376.666754] Key type ._llcrypt registered [ 5376.669898] Key type .llcrypt registered [ 5378.201445] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 01:57:27 (1787378247) [ 5380.983764] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 5382.926332] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 01:57:32 (1787378252) [ 5385.637425] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 5387.566985] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 01:57:36 (1787378256) [ 5390.427925] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5390.469883] alg: No test for adler32 (adler32-zlib) [ 5391.578314] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 5391.842068] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5393.551233] Key type lgssc registered [ 5395.167515] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5447.256409] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5452.675812] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5475.406536] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5478.180891] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 5480.976053] Lustre: Unmounted lustre-client [ 5509.410151] Key type lgssc unregistered [ 5509.690144] LNet: 119355:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5509.698080] LNetError: 119355:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5509.732179] LNet: Removed LNI 192.168.201.3@tcp [ 5510.590239] Key type .llcrypt unregistered [ 5510.592383] Key type ._llcrypt unregistered [ 5528.114768] Key type ._llcrypt registered [ 5528.121502] Key type .llcrypt registered [ 5529.769185] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 01:59:58 (1787378398) [ 5531.297344] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5531.327026] alg: No test for adler32 (adler32-zlib) [ 5532.421456] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 5532.855959] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5534.607319] Key type lgssc registered [ 5535.915170] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5596.972372] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5602.959922] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5619.147803] Key type lgssc unregistered [ 5619.405743] LNet: 122449:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5619.413891] LNetError: 122449:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5619.441929] LNet: Removed LNI 192.168.201.3@tcp [ 5619.979766] Key type .llcrypt unregistered [ 5619.981169] Key type ._llcrypt unregistered [ 5633.864807] Key type ._llcrypt registered [ 5633.868681] Key type .llcrypt registered [ 5635.025428] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 02:01:44 (1787378504) [ 5642.309276] Key type .llcrypt unregistered [ 5642.317185] Key type ._llcrypt unregistered [ 5649.153495] Key type ._llcrypt registered [ 5649.156451] Key type .llcrypt registered [ 5649.626509] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5649.638545] alg: No test for adler32 (adler32-zlib) [ 5650.656966] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 5650.825944] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5652.439303] Key type lgssc registered [ 5653.985546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5742.539769] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5777.900935] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5783.802573] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5823.792389] Key type lgssc unregistered [ 5824.005922] LNet: 126322:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5824.010399] LNetError: 126322:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5824.022101] LNet: Removed LNI 192.168.201.3@tcp [ 5824.745878] Key type .llcrypt unregistered [ 5824.748992] Key type ._llcrypt unregistered [ 5838.243509] Key type ._llcrypt registered [ 5838.245760] Key type .llcrypt registered [ 5839.301714] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 02:05:09 (1787378709) [ 5842.022877] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5842.121790] alg: No test for adler32 (adler32-zlib) [ 5843.342934] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 5843.614795] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5845.336184] Key type lgssc registered [ 5846.697992] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5890.838931] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5896.024614] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5914.330836] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5916.892423] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 5922.289841] Lustre: lustre-MDT0000-mdc-ffff9888c64df000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5938.655392] Lustre: 127295:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787378793/real 1787378793] req@00000000fa92a74e x1874202422414464/t0(0) o400->MGC192.168.201.103@tcp@192.168.201.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1787378809 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5938.711490] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 5944.918316] Lustre: lustre-MDT0000-mdc-ffff9888c64df000: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 5948.972930] Lustre: Evicted from MGS (at 192.168.201.103@tcp) after server handle changed from 0x76ce6b79de5e381c to 0x76ce6b79de5e3afb [ 5948.986106] Lustre: MGC192.168.201.103@tcp: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 5969.432257] Lustre: Unmounted lustre-client [ 5992.898366] Key type lgssc unregistered [ 5993.220657] LNet: 130340:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5993.231939] LNetError: 130340:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5993.268087] LNet: Removed LNI 192.168.201.3@tcp [ 5994.165890] Key type .llcrypt unregistered [ 5994.167667] Key type ._llcrypt unregistered [ 6009.168328] Key type ._llcrypt registered [ 6009.170849] Key type .llcrypt registered [ 6010.479584] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 02:07:59 (1787378879) [ 6012.744564] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6012.879461] alg: No test for adler32 (adler32-zlib) [ 6013.990952] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 6014.274478] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 6016.063270] Key type lgssc registered [ 6017.125779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6064.485405] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6069.852313] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6092.177961] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6095.330783] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 6112.974139] Lustre: Unmounted lustre-client [ 6113.549095] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 6129.822586] Lustre: Unmounted lustre-client [ 6157.260708] Key type lgssc unregistered [ 6157.524756] LNet: 134583:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6157.535286] LNetError: 134583:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6157.556170] LNet: Removed LNI 192.168.201.3@tcp [ 6158.190940] Key type .llcrypt unregistered [ 6158.194554] Key type ._llcrypt unregistered [ 6168.745872] Key type ._llcrypt registered [ 6168.747761] Key type .llcrypt registered [ 6169.913327] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 02:10:39 (1787379039) [ 6172.384591] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6172.408075] alg: No test for adler32 (adler32-zlib) [ 6173.545696] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 6173.892925] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 6175.599201] Key type lgssc registered [ 6176.615093] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6228.930694] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6235.391598] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6255.579454] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6258.365164] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 6293.829887] Lustre: Unmounted lustre-client [ 6333.218593] Key type lgssc unregistered [ 6333.589897] LNet: 137934:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6333.606233] LNetError: 137934:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6333.637593] LNet: Removed LNI 192.168.201.3@tcp [ 6334.553819] Key type .llcrypt unregistered [ 6334.555631] Key type ._llcrypt unregistered [ 6353.255708] Key type ._llcrypt registered [ 6353.263722] Key type .llcrypt registered [ 6354.433871] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 02:13:43 (1787379223) [ 6356.957308] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6356.992620] alg: No test for adler32 (adler32-zlib) [ 6358.055611] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 6358.305656] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 6359.983539] Key type lgssc registered [ 6361.344735] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6416.842749] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6423.490515] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6446.490654] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6448.396177] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 6451.530715] Lustre: Unmounted lustre-client [ 6451.892242] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 6460.177678] Lustre: Unmounted lustre-client [ 6487.056413] Key type lgssc unregistered [ 6487.316033] LNet: 141984:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6487.322322] LNetError: 141984:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6487.333328] LNet: Removed LNI 192.168.201.3@tcp [ 6488.054554] Key type .llcrypt unregistered [ 6488.057932] Key type ._llcrypt unregistered [ 6500.658530] Key type ._llcrypt registered [ 6500.661372] Key type .llcrypt registered [ 6502.472676] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 02:16:11 (1787379371) [ 6504.562430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6504.628478] alg: No test for adler32 (adler32-zlib) [ 6505.939169] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 6506.379356] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 6508.095200] Key type lgssc registered [ 6510.396707] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6569.502172] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6576.333887] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6598.237684] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6610.499282] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 6613.365394] Lustre: Unmounted lustre-client [ 6613.984370] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 6621.551693] Lustre: Unmounted lustre-client [ 6657.494712] Key type lgssc unregistered [ 6657.884705] LNet: 145612:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6657.890357] LNetError: 145612:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6657.901554] LNet: Removed LNI 192.168.201.3@tcp [ 6658.701507] Key type .llcrypt unregistered [ 6658.707086] Key type ._llcrypt unregistered [ 6670.782516] Key type ._llcrypt registered [ 6670.793080] Key type .llcrypt registered [ 6672.567126] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 02:19:01 (1787379541) [ 6675.355058] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6675.590945] alg: No test for adler32 (adler32-zlib) [ 6676.927693] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 6677.287278] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 6679.039163] Key type lgssc registered [ 6680.378268] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6736.859623] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6744.088397] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6765.147283] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6766.905899] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 6788.382158] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6790.345818] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6811.668199] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6816.269516] Lustre: Unmounted lustre-client [ 6816.664078] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6816.776568] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 6829.126385] Lustre: Unmounted lustre-client [ 6875.152678] Key type lgssc unregistered [ 6875.503750] LNet: 149678:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6875.515712] LNetError: 149678:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6875.548524] LNet: Removed LNI 192.168.201.3@tcp [ 6876.461835] Key type .llcrypt unregistered [ 6876.468449] Key type ._llcrypt unregistered [ 6909.535541] Key type ._llcrypt registered [ 6909.537597] Key type .llcrypt registered [ 6910.878127] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 02:23:00 (1787379780) [ 6913.860147] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6913.918875] alg: No test for adler32 (adler32-zlib) [ 6915.163306] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 6915.425659] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 6917.111216] Key type lgssc registered [ 6918.350351] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6977.695823] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6984.310933] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7010.244918] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7011.806592] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 7170.268996] Lustre: Unmounted lustre-client [ 7170.987619] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 7175.043668] Lustre: Unmounted lustre-client [ 7175.732306] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 7178.669496] Lustre: Unmounted lustre-client [ 7208.836291] Key type lgssc unregistered [ 7209.158837] LNet: 154875:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7209.169886] LNetError: 154875:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7209.190104] LNet: Removed LNI 192.168.201.3@tcp [ 7210.217258] Key type .llcrypt unregistered [ 7210.221103] Key type ._llcrypt unregistered [ 7228.670408] Key type ._llcrypt registered [ 7228.675302] Key type .llcrypt registered [ 7230.562759] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 02:28:19 (1787380099) [ 7233.800457] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7233.827806] alg: No test for adler32 (adler32-zlib) [ 7235.093274] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 7235.394584] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7237.120079] Key type lgssc registered [ 7238.389919] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7295.229100] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7301.701705] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7325.352592] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7328.166643] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 7335.992994] Lustre: Unmounted lustre-client [ 7336.416488] Lustre: Mounted lustre-client - version 2.17.57_45_gb800aec [ 7339.370477] Lustre: Unmounted lustre-client [ 7368.283815] Key type lgssc unregistered [ 7368.710353] LNet: 158568:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7368.726831] LNetError: 158568:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7368.759857] LNet: Removed LNI 192.168.201.3@tcp [ 7369.663197] Key type .llcrypt unregistered [ 7369.666239] Key type ._llcrypt unregistered [ 7386.709351] Key type ._llcrypt registered [ 7386.711196] Key type .llcrypt registered [ 7388.201289] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 02:30:57 (1787380257) [ 7389.611756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7389.727933] alg: No test for adler32 (adler32-zlib) [ 7390.781891] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 7391.038249] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7392.767232] Key type lgssc registered [ 7394.031212] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7416.864351] LustreError: 160263:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 7426.015837] LustreError: 160272:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 7469.919969] LNet: 1 local NIs in recovery (showing 1): 192.168.201.3@tcp [ 7542.623896] LNet: 1 local NIs in recovery (showing 1): 192.168.201.3@tcp [ 7573.475670] 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 [ 7573.502291] Lustre: Unmounted lustre-client [ 7573.513678] LustreError: 160263:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 7586.291680] Key type lgssc unregistered [ 7597.983875] LNet: 160683:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7597.995905] LNetError: 160683:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7672.817825] LNet: Removed LNI 192.168.201.3@tcp [ 7673.859174] Key type .llcrypt unregistered [ 7673.860857] Key type ._llcrypt unregistered [ 7716.814611] Key type ._llcrypt registered [ 7716.818236] Key type .llcrypt registered [ 7717.357572] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7717.376913] alg: No test for adler32 (adler32-zlib) [ 7718.544895] Lustre: Lustre: Build Version: 2.17.57_45_gb800aec [ 7718.862821] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7720.655538] Key type lgssc registered [ 7722.245520] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7769.768206] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7446 sec ========== 02:37:19 (1787380639) [ 7771.937579] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 02:37:21 (1787380641) === [ 7776.378535] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 02:37:25 (1787380645) === [ 7813.874544] Key type lgssc unregistered [ 7814.177917] LNet: 164925:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7814.186783] LNetError: 164925:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7814.220201] LNet: Removed LNI 192.168.201.3@tcp [ 7815.153560] Key type .llcrypt unregistered [ 7815.155350] Key type ._llcrypt unregistered