[ 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 448002923 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 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.001012] APIC: Switch to symmetric I/O mode setup [ 0.003344] x2apic enabled [ 0.004009] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.007425] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.009017] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.010013] pid_max: default: 32768 minimum: 301 [ 0.011139] LSM: Security Framework initializing [ 0.012066] Yama: becoming mindful. [ 0.013041] SELinux: Initializing. [ 0.014066] *** VALIDATE selinux *** [ 0.022191] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027330] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028153] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030096] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031118] *** VALIDATE tmpfs *** [ 0.032478] *** VALIDATE proc *** [ 0.033231] *** VALIDATE cgroup *** [ 0.034008] *** VALIDATE cgroup2 *** [ 0.036266] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038080] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040030] Spectre V2 : User space: Vulnerable [ 0.041008] Speculative Store Bypass: Vulnerable [ 0.044420] debug: unmapping init [mem 0xffffffff9b659000-0xffffffff9b660fff] [ 0.047217] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048714] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049029] ... version: 2 [ 0.050016] ... bit width: 48 [ 0.051013] ... generic registers: 4 [ 0.052010] ... value mask: 0000ffffffffffff [ 0.053011] ... max period: 00007fffffffffff [ 0.054014] ... fixed-purpose events: 3 [ 0.055014] ... event mask: 000000070000000f [ 0.056317] rcu: Hierarchical SRCU implementation. [ 0.058285] smp: Bringing up secondary CPUs ... [ 0.059451] x86: Booting SMP configuration: [ 0.060040] .... node #0, CPUs: #1 #2 #3 [ 0.063423] smp: Brought up 1 node, 4 CPUs [ 0.065010] smpboot: Max logical packages: 1 [ 0.066017] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.189314] node 0 deferred pages initialised in 121ms [ 0.193265] devtmpfs: initialized [ 0.194309] x86/mm: Memory block size: 128MB [ 0.196681] gcov: version magic: 0x41383552 [ 0.200363] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.204175] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.207598] pinctrl core: initialized pinctrl subsystem [ 0.210391] [ 0.210967] ************************************************************* [ 0.213029] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.216031] ** ** [ 0.219027] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.221023] ** ** [ 0.224030] ** This means that this kernel is built to expose internal ** [ 0.226026] ** IOMMU data structures, which may compromise security on ** [ 0.229040] ** your system. ** [ 0.232029] ** ** [ 0.234023] ** If you see this message and you are not debugging the ** [ 0.236027] ** kernel, report this immediately to your vendor! ** [ 0.239027] ** ** [ 0.241029] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.243028] ************************************************************* [ 0.247403] NET: Registered protocol family 16 [ 0.248642] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.252072] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.254081] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.258118] cpuidle: using governor menu [ 0.259485] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.262534] PCI: Using configuration type 1 for base access [ 0.264143] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.275082] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.276034] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.278059] cryptd: max_cpu_qlen set to 1000 [ 0.280345] ACPI: Added _OSI(Module Device) [ 0.282029] ACPI: Added _OSI(Processor Device) [ 0.284033] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.286026] ACPI: Added _OSI(Processor Aggregator Device) [ 0.294322] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.302385] ACPI: Interpreter enabled [ 0.303078] ACPI: PM: (supports S0 S3 S4 S5) [ 0.305011] ACPI: Using IOAPIC for interrupt routing [ 0.306131] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.309548] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.320854] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.323039] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.325022] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.329091] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.334487] acpiphp: Slot [2] registered [ 0.336123] acpiphp: Slot [5] registered [ 0.338206] acpiphp: Slot [6] registered [ 0.340134] acpiphp: Slot [3] registered [ 0.341141] acpiphp: Slot [4] registered [ 0.343100] acpiphp: Slot [7] registered [ 0.344091] acpiphp: Slot [8] registered [ 0.346094] acpiphp: Slot [9] registered [ 0.347087] acpiphp: Slot [10] registered [ 0.349124] acpiphp: Slot [11] registered [ 0.351103] acpiphp: Slot [12] registered [ 0.352146] acpiphp: Slot [13] registered [ 0.354129] acpiphp: Slot [14] registered [ 0.356117] acpiphp: Slot [15] registered [ 0.357101] acpiphp: Slot [16] registered [ 0.359081] acpiphp: Slot [17] registered [ 0.361092] acpiphp: Slot [18] registered [ 0.362134] acpiphp: Slot [19] registered [ 0.364119] acpiphp: Slot [20] registered [ 0.366101] acpiphp: Slot [21] registered [ 0.367107] acpiphp: Slot [22] registered [ 0.369103] acpiphp: Slot [23] registered [ 0.370096] acpiphp: Slot [24] registered [ 0.372093] acpiphp: Slot [25] registered [ 0.374113] acpiphp: Slot [26] registered [ 0.375115] acpiphp: Slot [27] registered [ 0.377098] acpiphp: Slot [28] registered [ 0.378106] acpiphp: Slot [29] registered [ 0.380094] acpiphp: Slot [30] registered [ 0.381093] acpiphp: Slot [31] registered [ 0.382072] PCI host bridge to bus 0000:00 [ 0.384027] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.386023] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.389024] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.391028] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.394037] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.397031] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.398193] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.400988] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.405487] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.413016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.418065] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.421022] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.424031] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.426021] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.430635] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.434203] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.438046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.440809] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.445009] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.457016] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.461792] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.467226] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.473016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.480016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.497015] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.507387] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.517016] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.524015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.545016] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.557211] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.560502] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.563517] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.565380] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.569263] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.574029] iommu: Default domain type: Passthrough [ 0.576504] SCSI subsystem initialized [ 0.578124] ACPI: bus type USB registered [ 0.580132] usbcore: registered new interface driver usbfs [ 0.582079] usbcore: registered new interface driver hub [ 0.584088] usbcore: registered new device driver usb [ 0.585212] pps_core: LinuxPPS API ver. 1 registered [ 0.587016] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.591058] PTP clock support registered [ 0.593140] EDAC MC: Ver: 3.0.0 [ 0.597075] PCI: Using ACPI for IRQ routing [ 0.598787] NetLabel: Initializing [ 0.599011] NetLabel: domain hash size = 128 [ 0.600009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.601131] NetLabel: unlabeled traffic allowed by default [ 0.603191] vgaarb: loaded [ 0.605349] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.607017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.616296] clocksource: Switched to clocksource kvm-clock [ 0.730356] VFS: Disk quotas dquot_6.6.0 [ 0.732342] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.735245] *** VALIDATE ramfs *** [ 0.736647] *** VALIDATE hugetlbfs *** [ 0.738469] pnp: PnP ACPI init [ 0.742228] pnp: PnP ACPI: found 6 devices [ 0.763044] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.766708] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.769110] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.771632] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.774344] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.776906] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.780037] NET: Registered protocol family 2 [ 0.782643] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.787808] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.791679] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.797694] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.801545] TCP: Hash tables configured (established 65536 bind 65536) [ 0.805579] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.808644] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.811697] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.815051] NET: Registered protocol family 1 [ 0.817696] RPC: Registered named UNIX socket transport module. [ 0.820355] RPC: Registered udp transport module. [ 0.822199] RPC: Registered tcp transport module. [ 0.824054] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.827335] NET: Registered protocol family 44 [ 0.829178] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.832045] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.834712] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.837636] PCI: CLS 0 bytes, default 64 [ 0.839193] Unpacking initramfs... [ 2.225350] debug: unmapping init [mem 0xffff9beffcc64000-0xffff9beffffcffff] [ 2.229424] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.231663] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.234448] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 2.710060] Initialise system trusted keyrings [ 2.711777] Key type blacklist registered [ 2.713942] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.722395] zbud: loaded [ 2.725900] *** VALIDATE nfs *** [ 2.727172] *** VALIDATE nfs4 *** [ 2.728762] pstore: using deflate compression [ 2.732144] Platform Keyring initialized [ 2.851354] NET: Registered protocol family 38 [ 2.855037] Key type asymmetric registered [ 2.856694] Asymmetric key parser 'x509' registered [ 2.859184] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.862402] io scheduler mq-deadline registered [ 2.864374] io scheduler kyber registered [ 2.866506] io scheduler bfq registered [ 2.868866] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.872767] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.876345] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.880192] ACPI: Power Button [PWRF] [ 2.886098] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.893494] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.905055] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.934345] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.962940] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.970136] Non-volatile memory driver v1.3 [ 2.972318] Linux agpgart interface v0.103 [ 3.010086] virtio_blk virtio1: [vda] 145160 512-byte logical blocks (74.3 MB/70.9 MiB) [ 3.013152] vda: detected capacity change from 0 to 74321920 [ 3.030069] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.033093] vdb: detected capacity change from 0 to 1073741824 [ 3.040412] libphy: Fixed MDIO Bus: probed [ 3.055733] usbcore: registered new interface driver usbserial_generic [ 3.057909] usbserial: USB Serial support registered for generic [ 3.059721] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.063737] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.065400] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.067126] mousedev: PS/2 mouse device common for all mice [ 3.074801] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.077041] rtc_cmos 00:05: RTC can wake from S4 [ 3.082927] rtc_cmos 00:05: registered as rtc0 [ 3.084268] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.085653] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.093357] intel_pstate: CPU model not supported [ 3.093776] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.099520] hid: raw HID events driver (C) Jiri Kosina [ 3.101352] usbcore: registered new interface driver usbhid [ 3.103186] usbhid: USB HID core driver [ 3.104550] drop_monitor: Initializing network drop monitor service [ 3.106557] Initializing XFRM netlink socket [ 3.108173] NET: Registered protocol family 10 [ 3.110633] Segment Routing with IPv6 [ 3.111956] NET: Registered protocol family 17 [ 3.115974] mpls_gso: MPLS GSO support [ 3.121854] RAS: Correctable Errors collector initialized. [ 3.124520] AVX version of gcm_enc/dec engaged. [ 3.126435] AES CTR mode by8 optimization enabled [ 3.217568] sched_clock: Marking stable (3217546626, 0)->(4153884244, -936337618) [ 3.220863] registered taskstats version 1 [ 3.223033] Loading compiled-in X.509 certificates [ 3.224770] zswap: loaded using pool lzo/zbud [ 3.254312] Key type big_key registered [ 3.268309] Key type encrypted registered [ 3.270467] ima: No TPM chip found, activating TPM-bypass! [ 3.272168] ima: Allocated hash algorithm: sha1 [ 3.274650] ima: No architecture policies found [ 3.276837] evm: Initialising EVM extended attributes: [ 3.278686] evm: security.selinux [ 3.280215] evm: security.ima [ 3.281343] evm: security.capability [ 3.282347] evm: HMAC attrs: 0x1 [ 3.285284] rtc_cmos 00:05: setting system clock to 2026-06-23 12:25:05 UTC (1782217505) [ 3.294434] debug: unmapping init [mem 0xffffffff9c603000-0xffffffff9c7fffff] [ 3.297689] debug: unmapping init [mem 0xffffffff9b382000-0xffffffff9b658fff] [ 3.310237] Write protecting the kernel read-only data: 28672k [ 3.313867] debug: unmapping init [mem 0xffffffff99a03000-0xffffffff99bfffff] [ 3.316240] debug: unmapping init [mem 0xffffffff9a314000-0xffffffff9a3fffff] [ 3.348619] 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.357714] systemd[1]: Detected virtualization kvm. [ 3.359849] systemd[1]: Detected architecture x86-64. [ 3.364835] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.396759] systemd[1]: No hostname configured. [ 3.398672] systemd[1]: Set hostname to . [ 3.401040] random: systemd: uninitialized urandom read (16 bytes read) [ 3.403838] systemd[1]: Initializing machine ID from random generator. [ 3.467718] random: ln: uninitialized urandom read (6 bytes read) [ 3.586902] random: systemd: uninitialized urandom read (16 bytes read) [ 3.589949] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.597299] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.602833] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. Starting Setup Virtual Console... [ OK ] Reached target Swap. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Timers. Starting Apply Kernel Variables... [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.319911] device-mapper: uevent: version 1.0.3 [ 4.322656] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ 5.036927] random: fast init done [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.079838] virtio_net virtio0 ens2: renamed from eth0 Starting dracut initqueue hook... [ 5.171703] scsi host0: ata_piix [ 5.192979] scsi host1: ata_piix [ 5.194813] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.197446] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.885663] dracut-initqueue[582]: RTNETLINK answers: File exists [ 9.787587] random: crng init done [ 9.788993] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.292284] 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. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ 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... [ 11.397293] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.685275] SELinux: Disabled at runtime. [ 11.746699] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.756679] systemd[1]: Detected virtualization kvm. [ 11.758941] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.324166] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.328243] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.334617] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.339018] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.342403] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.349111] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.358106] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ 12.419821] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting Kernel Debug File System... [ OK ] Stopped target Initrd Root File System. Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Huge Pages File System... [ OK ] Stopped target Initrd File Systems. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Journal Service. [ 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 Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.828194] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.119637] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.127376] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.252808] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.278182] EDAC sbridge: Ver: 1.1.2 [ 14.267324] Key type dns_resolver registered [ 14.570177] NFS: Registering the id_resolver key type [ 14.572277] Key type id_resolver registered [ 14.575608] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Login Service. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg416-client login: [ 70.943129] libcfs: loading out-of-tree module taints kernel. [ 71.070132] Key type ._llcrypt registered [ 71.072826] Key type .llcrypt registered [ 71.754805] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 71.768985] alg: No test for adler32 (adler32-zlib) [ 73.178555] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 74.020333] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 75.848179] Key type lgssc registered [ 77.623665] Lustre: Echo OBD driver; http://www.lustre.org/ [ 171.599130] hrtimer: interrupt took 5373550 ns [ 250.377798] Lustre: Mounted lustre-client [ 255.317813] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 268.500529] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing check_logdir /tmp/testlogs/ [ 273.311289] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing yml_node [ 275.937432] Lustre: lustre-OST0000-osc-ffff9bf0486de800: disconnect after 24s idle [ 277.669165] Lustre: DEBUG MARKER: Client: 2.17.54.83 [ 280.281655] Lustre: DEBUG MARKER: MDS: 2.17.54.83 [ 283.030830] Lustre: DEBUG MARKER: OSS: 2.17.54.83 [ 284.648621] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Jun 23 08:29:45 EDT 2026 [ 302.598235] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 304.183688] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 307.179628] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 317.531223] Lustre: Unmounted lustre-client [ 443.417704] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 448.531319] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 470.876902] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 506.520355] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 08:33:27 (1782218007) [ 538.573880] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 544.439769] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 567.853921] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 568.569379] Lustre: Mounted lustre-client [ 576.912399] Lustre: Unmounted lustre-client [ 606.147440] Key type lgssc unregistered [ 606.440668] LNet: 14613:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 606.452123] LNetError: 14613:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 606.477357] LNet: Removed LNI 192.168.204.16@tcp [ 607.429201] Key type .llcrypt unregistered [ 607.430983] Key type ._llcrypt unregistered [ 624.366230] Key type ._llcrypt registered [ 624.368549] Key type .llcrypt registered [ 625.716079] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 08:35:26 (1782218126) [ 628.247483] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 628.298166] alg: No test for adler32 (adler32-zlib) [ 629.440437] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 629.761660] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 631.472910] Key type lgssc registered [ 632.890966] Lustre: Echo OBD driver; http://www.lustre.org/ [ 686.094161] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 692.043982] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 712.328805] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 723.940086] Lustre: Mounted lustre-client [ 726.560269] Lustre: Unmounted lustre-client [ 749.662948] Key type lgssc unregistered [ 749.938428] LNet: 18261:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 749.946771] LNetError: 18261:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 749.963866] LNet: Removed LNI 192.168.204.16@tcp [ 750.601485] Key type .llcrypt unregistered [ 750.605487] Key type ._llcrypt unregistered [ 765.629177] Key type ._llcrypt registered [ 765.634596] Key type .llcrypt registered [ 767.128000] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 08:37:47 (1782218267) [ 769.430772] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 769.466279] alg: No test for adler32 (adler32-zlib) [ 770.671262] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 771.031658] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 772.800197] Key type lgssc registered [ 774.226260] Lustre: Echo OBD driver; http://www.lustre.org/ [ 824.741634] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 831.024932] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 858.739375] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 869.978145] Lustre: Mounted lustre-client [ 872.740298] Lustre: Unmounted lustre-client [ 920.829815] Key type lgssc unregistered [ 921.127779] LNet: 21891:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 921.136499] LNetError: 21891:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 921.152054] LNet: Removed LNI 192.168.204.16@tcp [ 921.940710] Key type .llcrypt unregistered [ 921.944065] Key type ._llcrypt unregistered [ 938.477969] Key type ._llcrypt registered [ 938.490574] Key type .llcrypt registered [ 939.770332] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 08:40:40 (1782218440) [ 942.047117] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 942.110886] alg: No test for adler32 (adler32-zlib) [ 943.266288] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 943.584287] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 945.313512] Key type lgssc registered [ 946.696773] Lustre: Echo OBD driver; http://www.lustre.org/ [ 999.224258] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1004.967357] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1024.763789] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1027.407291] Lustre: Mounted lustre-client [ 1034.821609] Lustre: Unmounted lustre-client [ 1056.273319] Key type lgssc unregistered [ 1056.526558] LNet: 25475:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1056.531697] LNetError: 25475:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1056.545321] LNet: Removed LNI 192.168.204.16@tcp [ 1057.118527] Key type .llcrypt unregistered [ 1057.123380] Key type ._llcrypt unregistered [ 1071.992541] Key type ._llcrypt registered [ 1071.996343] Key type .llcrypt registered [ 1073.232417] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 08:42:54 (1782218574) [ 1075.666539] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1075.747521] alg: No test for adler32 (adler32-zlib) [ 1076.918131] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 1077.193189] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 1078.890129] Key type lgssc registered [ 1080.205616] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1126.692881] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1132.011288] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1152.562553] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1154.155622] Lustre: Mounted lustre-client [ 1159.157812] Lustre: lustre-OST0000-osc-ffff9bf0430de800: Connection to lustre-OST0000 (at 192.168.204.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1168.044438] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1178.424310] Lustre: Unmounted lustre-client [ 1212.813224] Key type lgssc unregistered [ 1213.068893] LNet: 29063:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1213.080325] LNetError: 29063:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1213.096161] LNet: Removed LNI 192.168.204.16@tcp [ 1213.776385] Key type .llcrypt unregistered [ 1213.786120] Key type ._llcrypt unregistered [ 1228.661495] Key type ._llcrypt registered [ 1228.665457] Key type .llcrypt registered [ 1230.119687] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 08:45:30 (1782218730) [ 1232.597874] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1232.634950] alg: No test for adler32 (adler32-zlib) [ 1233.820639] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 1234.135025] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 1235.840290] Key type lgssc registered [ 1237.111645] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1286.325135] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1291.053887] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1311.185440] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1314.265780] Lustre: Mounted lustre-client [ 1319.406853] Lustre: lustre-MDT0000-mdc-ffff9bf045b94000: Connection to lustre-MDT0000 (at 192.168.204.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1329.650370] Lustre: lustre-MDT0001-mdc-ffff9bf045b94000: Connection to lustre-MDT0001 (at 192.168.204.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1339.130348] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1344.992370] Lustre: 30023:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782218831/real 1782218831] req@00000000ae63f752 x1868791793326208/t0(0) o400->MGC192.168.204.116@tcp@192.168.204.116@tcp:26/25 lens 224/224 e 0 to 1 dl 1782218847 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1345.025427] LustreError: MGC192.168.204.116@tcp: Connection to MGS (at 192.168.204.116@tcp) was lost; in progress operations using this service will fail [ 1349.463902] Lustre: Unmounted lustre-client [ 1369.257510] Key type lgssc unregistered [ 1369.609113] LNet: 32689:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1369.617828] LNetError: 32689:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1369.649413] LNet: Removed LNI 192.168.204.16@tcp [ 1370.614767] Key type .llcrypt unregistered [ 1370.616691] Key type ._llcrypt unregistered [ 1390.512342] Key type ._llcrypt registered [ 1390.514989] Key type .llcrypt registered [ 1392.091872] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 08:48:12 (1782218892) [ 1394.490255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1394.510656] alg: No test for adler32 (adler32-zlib) [ 1395.699520] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 1395.898578] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 1397.585391] Key type lgssc registered [ 1398.699072] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1482.131931] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1487.776581] LustreError: 34885:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.204.116@tcp: failed processing log, type 1: rc = -110 [ 1496.034192] LustreError: 34895:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1646.561106] LustreError: MGC192.168.204.116@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 [ 1646.579759] Lustre: Unmounted lustre-client [ 1646.586228] LustreError: 34885:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1659.238223] Key type lgssc unregistered [ 1659.427493] LNet: 35318:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1659.439555] LNetError: 35318:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1659.461326] LNet: Removed LNI 192.168.204.16@tcp [ 1660.174540] Key type .llcrypt unregistered [ 1660.186873] Key type ._llcrypt unregistered [ 1673.638507] Key type ._llcrypt registered [ 1673.640314] Key type .llcrypt registered [ 1674.948142] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 08:52:55 (1782219175) [ 1677.528143] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1677.577441] alg: No test for adler32 (adler32-zlib) [ 1678.699829] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 1678.976855] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 1680.672212] Key type lgssc registered [ 1682.005190] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1729.934499] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1735.337507] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1754.752784] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1755.254890] LustreError: 38382:0:(llite_lib.c:1436:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1755.267835] Lustre: Unmounted wrong.lustre-client [ 1755.274911] LustreError: 38382:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1783.076737] Key type lgssc unregistered [ 1783.305438] LNet: 38798:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1783.309561] LNetError: 38798:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1783.321375] LNet: Removed LNI 192.168.204.16@tcp [ 1783.861127] Key type .llcrypt unregistered [ 1783.865221] Key type ._llcrypt unregistered [ 1800.479888] Key type ._llcrypt registered [ 1800.482695] Key type .llcrypt registered [ 1802.051839] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 08:55:02 (1782219302) [ 1804.135734] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1804.234761] alg: No test for adler32 (adler32-zlib) [ 1805.214142] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 1805.460177] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 1807.176328] Key type lgssc registered [ 1808.617599] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1890.937225] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1926.887823] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1932.980757] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1944.760401] Lustre: Mounted lustre-client [ 1947.165435] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1957.480612] Lustre: Unmounted lustre-client [ 2003.260689] Key type lgssc unregistered [ 2003.609511] LNet: 42348:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2003.625324] LNetError: 42348:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2003.646914] LNet: Removed LNI 192.168.204.16@tcp [ 2004.304045] Key type .llcrypt unregistered [ 2004.313569] Key type ._llcrypt unregistered [ 2021.802494] Key type ._llcrypt registered [ 2021.808711] Key type .llcrypt registered [ 2023.029477] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 08:58:43 (1782219523) [ 2025.716759] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2025.765669] alg: No test for adler32 (adler32-zlib) [ 2026.983466] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 2027.322928] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 2028.992235] Key type lgssc registered [ 2030.083473] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2080.623845] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2086.701302] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2111.709920] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2112.449671] LustreError: 43309:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2117.600556] LustreError: 45409:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.204.116@tcp: failed processing log, type 1: rc = -110 [ 2122.456653] LustreError: 43309:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2123.761420] Lustre: Mounted lustre-client [ 2125.624425] Lustre: Unmounted lustre-client [ 2160.406294] Key type lgssc unregistered [ 2160.856884] LNet: 45880:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2160.867560] LNetError: 45880:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2160.895505] LNet: Removed LNI 192.168.204.16@tcp [ 2161.790041] Key type .llcrypt unregistered [ 2161.796883] Key type ._llcrypt unregistered [ 2178.167100] Key type ._llcrypt registered [ 2178.172291] Key type .llcrypt registered [ 2179.217700] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 09:01:20 (1782219680) [ 2180.989307] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2182.571346] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 09:01:23 (1782219683) [ 2183.783206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2183.816428] alg: No test for adler32 (adler32-zlib) [ 2185.059243] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 2185.410659] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 2187.168229] Key type lgssc registered [ 2188.414276] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2214.051429] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 09:01:54 (1782219714) [ 2252.017714] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2258.105247] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2279.468631] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2282.186304] Lustre: Mounted lustre-client [ 2292.723836] Lustre: lustre-MDT0000-mdc-ffff9bf05815f800: Connection to lustre-MDT0000 (at 192.168.204.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2307.873142] Lustre: 47103:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782219794/real 1782219794] req@000000001d2d9cc0 x1868792790520064/t0(0) o400->MGC192.168.204.116@tcp@192.168.204.116@tcp:26/25 lens 224/224 e 0 to 1 dl 1782219810 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2307.933201] LustreError: MGC192.168.204.116@tcp: Connection to MGS (at 192.168.204.116@tcp) was lost; in progress operations using this service will fail [ 2314.713467] Lustre: lustre-MDT0000-mdc-ffff9bf05815f800: Connection restored to 192.168.204.116@tcp (at 192.168.204.116@tcp) [ 2317.838430] Lustre: Evicted from MGS (at 192.168.204.116@tcp) after server handle changed from 0x642bd4fcbd0cb5c5 to 0x642bd4fcbd0cb937 [ 2317.867477] Lustre: MGC192.168.204.116@tcp: Connection restored to 192.168.204.116@tcp (at 192.168.204.116@tcp) [ 2319.689645] Lustre: Unmounted lustre-client [ 2347.537569] Key type lgssc unregistered [ 2347.861957] LNet: 50709:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2347.877097] LNetError: 50709:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2347.905774] LNet: Removed LNI 192.168.204.16@tcp [ 2348.721635] Key type .llcrypt unregistered [ 2348.723890] Key type ._llcrypt unregistered [ 2364.915984] Key type ._llcrypt registered [ 2364.917623] Key type .llcrypt registered [ 2366.424271] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 09:04:26 (1782219866) [ 2369.383661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2369.514898] alg: No test for adler32 (adler32-zlib) [ 2370.726101] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 2371.108599] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 2372.841103] Key type lgssc registered [ 2374.547828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2430.842476] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2436.328367] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2458.426889] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2461.425279] Lustre: Mounted lustre-client [ 2471.915139] Lustre: lustre-MDT0000-mdc-ffff9bf048d0c000: Connection to lustre-MDT0000 (at 192.168.204.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2488.288162] Lustre: 51670:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782219974/real 1782219974] req@00000000d17c7250 x1868792985555200/t0(0) o400->MGC192.168.204.116@tcp@192.168.204.116@tcp:26/25 lens 224/224 e 0 to 1 dl 1782219990 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2488.326738] LustreError: MGC192.168.204.116@tcp: Connection to MGS (at 192.168.204.116@tcp) was lost; in progress operations using this service will fail [ 2488.361704] Lustre: Evicted from MGS (at 192.168.204.116@tcp) after server handle changed from 0xd2ce107026d833df to 0xd2ce107026d8366a [ 2488.371686] Lustre: MGC192.168.204.116@tcp: Connection restored to 192.168.204.116@tcp (at 192.168.204.116@tcp) [ 2493.427519] Lustre: lustre-MDT0000-mdc-ffff9bf048d0c000: Connection restored to 192.168.204.116@tcp (at 192.168.204.116@tcp) [ 2500.388481] Lustre: Unmounted lustre-client [ 2530.802372] Key type lgssc unregistered [ 2531.022404] LNet: 54645:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2531.035464] LNetError: 54645:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2531.059092] LNet: Removed LNI 192.168.204.16@tcp [ 2531.567675] Key type .llcrypt unregistered [ 2531.570206] Key type ._llcrypt unregistered [ 2545.928886] Key type ._llcrypt registered [ 2545.931728] Key type .llcrypt registered [ 2547.557463] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 09:07:27 (1782220047) [ 2548.179333] Key type .llcrypt unregistered [ 2548.185655] Key type ._llcrypt unregistered [ 2554.733962] Key type ._llcrypt registered [ 2554.737294] Key type .llcrypt registered [ 2554.803582] Key type .llcrypt unregistered [ 2554.808835] Key type ._llcrypt unregistered [ 2559.899437] Key type ._llcrypt registered [ 2559.902499] Key type .llcrypt registered [ 2561.162901] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 09:07:41 (1782220061) [ 2563.524532] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2563.571736] alg: No test for adler32 (adler32-zlib) [ 2564.716623] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 2564.968833] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 2566.608186] Key type lgssc registered [ 2567.907619] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2621.753311] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2628.052832] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2650.134031] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2651.930541] Lustre: Mounted lustre-client [ 2657.502361] Lustre: Unmounted lustre-client [ 2657.942296] Lustre: Mounted lustre-client [ 2660.866766] Lustre: Unmounted lustre-client [ 2688.786876] Key type lgssc unregistered [ 2689.093461] LNet: 59035:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2689.111026] LNetError: 59035:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2689.139866] LNet: Removed LNI 192.168.204.16@tcp [ 2689.852211] Key type .llcrypt unregistered [ 2689.862469] Key type ._llcrypt unregistered [ 2705.197770] Key type ._llcrypt registered [ 2705.207711] Key type .llcrypt registered [ 2706.775070] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 09:10:07 (1782220207) [ 2709.406666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2709.500422] alg: No test for adler32 (adler32-zlib) [ 2710.600915] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 2710.915944] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 2712.640626] Key type lgssc registered [ 2714.223467] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2767.077906] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2774.015994] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2796.511558] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2798.037112] Lustre: Mounted lustre-client [ 2803.450604] Lustre: Unmounted lustre-client [ 2825.933517] Key type lgssc unregistered [ 2826.197650] LNet: 62578:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2826.210986] LNetError: 62578:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2826.236384] LNet: Removed LNI 192.168.204.16@tcp [ 2826.895639] Key type .llcrypt unregistered [ 2826.904258] Key type ._llcrypt unregistered [ 2841.697152] Key type ._llcrypt registered [ 2841.702608] Key type .llcrypt registered [ 2842.942886] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 09:12:23 (1782220343) [ 2844.917298] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2844.996468] alg: No test for adler32 (adler32-zlib) [ 2846.127438] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 2846.449461] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 2848.144261] Key type lgssc registered [ 2849.254344] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2900.576414] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2906.157535] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2923.940614] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2925.328079] Lustre: Mounted lustre-client [ 2936.340189] Lustre: Mounted lustre-client [ 2939.603451] Lustre: Unmounted lustre-client [ 2942.063152] Lustre: Unmounted lustre-client [ 2975.900747] Key type lgssc unregistered [ 2976.202981] LNet: 66211:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2976.213122] LNetError: 66211:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2976.245191] LNet: Removed LNI 192.168.204.16@tcp [ 2977.041783] Key type .llcrypt unregistered [ 2977.045290] Key type ._llcrypt unregistered [ 2991.430695] Key type ._llcrypt registered [ 2991.433867] Key type .llcrypt registered [ 2992.900931] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 09:14:53 (1782220493) [ 2995.187816] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2995.213572] alg: No test for adler32 (adler32-zlib) [ 2996.233503] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 2996.419739] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 2998.128704] Key type lgssc registered [ 2999.622605] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3085.178549] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3098.529638] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 09:16:39 (1782220599) [ 3130.760859] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3136.189165] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3158.110436] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3159.610415] Lustre: Mounted lustre-client [ 3170.757938] Lustre: Unmounted lustre-client [ 3199.859981] Key type lgssc unregistered [ 3200.305966] LNet: 70944:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3200.322209] LNetError: 70944:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3200.355116] LNet: Removed LNI 192.168.204.16@tcp [ 3201.594430] Key type .llcrypt unregistered [ 3201.602850] Key type ._llcrypt unregistered [ 3221.743365] Key type ._llcrypt registered [ 3221.745738] Key type .llcrypt registered [ 3223.127127] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 09:18:43 (1782220723) [ 3239.999516] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 09:19:00 (1782220740) [ 3242.374600] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3242.562329] alg: No test for adler32 (adler32-zlib) [ 3243.690898] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 3243.949799] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 3245.632359] Key type lgssc registered [ 3246.743256] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3294.869765] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3300.457915] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3320.223770] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3321.722318] Lustre: Mounted lustre-client [ 3326.335303] Lustre: Unmounted lustre-client [ 3372.338838] Key type lgssc unregistered [ 3372.789910] LNet: 75220:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3372.803362] LNetError: 75220:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3372.829395] LNet: Removed LNI 192.168.204.16@tcp [ 3373.756340] Key type .llcrypt unregistered [ 3373.768722] Key type ._llcrypt unregistered [ 3387.665270] Key type ._llcrypt registered [ 3387.705084] Key type .llcrypt registered [ 3388.076931] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3388.090355] alg: No test for adler32 (adler32-zlib) [ 3389.128628] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 3389.452838] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 3391.248300] Key type lgssc registered [ 3392.421515] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3474.764552] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3585.537083] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3590.440590] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3612.692859] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3652.705818] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 09:25:53 (1782221153) [ 3655.842314] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3745.772092] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3751.147987] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3773.829598] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3837.454680] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3842.672838] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3859.577924] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3862.222071] Lustre: Mounted lustre-client [ 3866.144685] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 3868.065986] Lustre: Unmounted lustre-client [ 3903.116464] Key type lgssc unregistered [ 3903.433342] LNet: 86822:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3903.443943] LNetError: 86822:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3903.485393] LNet: Removed LNI 192.168.204.16@tcp [ 3904.259360] Key type .llcrypt unregistered [ 3904.263371] Key type ._llcrypt unregistered [ 3931.369251] Key type ._llcrypt registered [ 3931.385714] Key type .llcrypt registered [ 3931.829166] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3931.844456] alg: No test for adler32 (adler32-zlib) [ 3933.054479] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 3933.378475] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 3935.080508] Key type lgssc registered [ 3936.153879] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4019.199240] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4024.803279] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4048.315473] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4086.888327] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 09:33:07 (1782221587) [ 4115.424515] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4121.741728] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4143.059808] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 09:34:03 (1782221643) [ 4208.875724] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4218.761887] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 09:35:19 (1782221719) [ 4249.464798] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4254.920944] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4275.167329] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4276.935412] Lustre: Mounted lustre-client [ 4277.644352] Lustre: Remounted lustre-client read-only [ 4280.195882] Lustre: Unmounted lustre-client [ 4313.179409] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 09:36:54 (1782221814) [ 4343.817850] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4349.187255] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4369.198382] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4415.777237] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 09:38:36 (1782221916) [ 4481.786695] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4513.090589] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4518.235902] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4562.347679] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 09:41:03 (1782222063) [ 4627.415206] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4656.939112] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4662.915020] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4684.747064] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4755.492572] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 09:44:16 (1782222256) [ 4757.245312] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 4758.908370] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 4760.270372] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 09:44:21 (1782222261) [ 4790.704617] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4795.738354] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4812.648960] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4840.620089] Lustre: Mounted lustre-client [ 4842.570726] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4852.916949] Lustre: Unmounted lustre-client [ 4875.446677] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4876.084358] Lustre: Mounted lustre-client [ 4893.445384] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9bf048d0b000.ost_server_uuid 50 [ 4895.284479] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9bf048d0b000.ost_server_uuid in FULL state after 0 sec [ 4897.401061] Lustre: Unmounted lustre-client [ 4946.466072] Key type lgssc unregistered [ 4946.799829] LNet: 106993:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4946.803517] LNetError: 106993:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4946.816762] LNet: Removed LNI 192.168.204.16@tcp [ 4947.795928] Key type .llcrypt unregistered [ 4947.799340] Key type ._llcrypt unregistered [ 4962.727045] Key type ._llcrypt registered [ 4962.728772] Key type .llcrypt registered [ 4963.874463] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 09:47:44 (1782222464) [ 4966.370887] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4966.390304] alg: No test for adler32 (adler32-zlib) [ 4967.482523] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 4967.708678] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 4969.456181] Key type lgssc registered [ 4970.746629] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5017.894629] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5025.055429] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5045.286465] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5047.137259] Lustre: Mounted lustre-client [ 5052.400688] Lustre: lustre-MDT0000-mdc-ffff9bf050bd0800: Connection to lustre-MDT0000 (at 192.168.204.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5055.158186] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5065.473480] LustreError: MGC192.168.204.116@tcp: Connection to MGS (at 192.168.204.116@tcp) was lost; in progress operations using this service will fail [ 5065.503062] Lustre: Unmounted lustre-client [ 5086.744938] LustreError: lustre-MDT0000-mdc-ffff9bf060106000: operation mds_connect to node 192.168.204.116@tcp failed: rc = -16 [ 5091.635376] LustreError: 110357:0:(lmv_obd.c:1466:lmv_statfs()) lustre-MDT0000-mdc-ffff9bf060106000: can't stat MDS #0: rc = -16 [ 5091.710058] Lustre: Unmounted lustre-client [ 5091.728618] LustreError: 110357:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 5115.353959] Key type lgssc unregistered [ 5115.729980] LNet: 110808:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5115.739216] LNetError: 110808:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5115.764640] LNet: Removed LNI 192.168.204.16@tcp [ 5116.424395] Key type .llcrypt unregistered [ 5116.433269] Key type ._llcrypt unregistered [ 5133.205127] Key type ._llcrypt registered [ 5133.208945] Key type .llcrypt registered [ 5134.495883] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 09:50:35 (1782222635) [ 5136.737194] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5136.792899] alg: No test for adler32 (adler32-zlib) [ 5137.835995] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 5138.117334] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 5139.816207] Key type lgssc registered [ 5141.056961] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5192.689859] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5200.730938] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5224.140329] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5236.792116] Lustre: Mounted lustre-client [ 5239.757245] Lustre: Unmounted lustre-client [ 5268.408753] Key type lgssc unregistered [ 5268.693768] LNet: 114315:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5268.701930] LNetError: 114315:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5268.722444] LNet: Removed LNI 192.168.204.16@tcp [ 5269.595579] Key type .llcrypt unregistered [ 5269.598433] Key type ._llcrypt unregistered [ 5286.644737] Key type ._llcrypt registered [ 5286.649287] Key type .llcrypt registered [ 5288.264559] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 09:53:08 (1782222788) [ 5290.665397] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 5292.276054] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 09:53:13 (1782222793) [ 5294.701989] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 5296.305602] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 09:53:17 (1782222797) [ 5298.589203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5298.622450] alg: No test for adler32 (adler32-zlib) [ 5299.681537] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 5299.933953] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 5301.584386] Key type lgssc registered [ 5302.686626] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5351.801167] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5357.119728] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5377.085183] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5379.515555] Lustre: Mounted lustre-client [ 5382.075644] Lustre: Unmounted lustre-client [ 5407.647109] Key type lgssc unregistered [ 5407.950664] LNet: 118480:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5407.954464] LNetError: 118480:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5407.978161] LNet: Removed LNI 192.168.204.16@tcp [ 5408.763461] Key type .llcrypt unregistered [ 5408.766296] Key type ._llcrypt unregistered [ 5424.907609] Key type ._llcrypt registered [ 5424.911168] Key type .llcrypt registered [ 5426.328961] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 09:55:26 (1782222926) [ 5428.438669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5428.472465] alg: No test for adler32 (adler32-zlib) [ 5429.655355] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 5429.982528] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 5431.696179] Key type lgssc registered [ 5433.064417] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5499.436759] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5504.500586] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5529.200874] Key type lgssc unregistered [ 5529.514588] LNet: 121548:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5529.522749] LNetError: 121548:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5529.543778] LNet: Removed LNI 192.168.204.16@tcp [ 5530.171523] Key type .llcrypt unregistered [ 5530.174454] Key type ._llcrypt unregistered [ 5543.320347] Key type ._llcrypt registered [ 5543.322794] Key type .llcrypt registered [ 5544.323089] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 09:57:25 (1782223045) [ 5551.945314] Key type .llcrypt unregistered [ 5551.947289] Key type ._llcrypt unregistered [ 5558.743188] Key type ._llcrypt registered [ 5558.746083] Key type .llcrypt registered [ 5559.152894] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5559.170540] alg: No test for adler32 (adler32-zlib) [ 5560.314762] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 5560.593300] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 5562.296328] Key type lgssc registered [ 5563.348989] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5644.587333] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5673.935280] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5679.674657] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5717.032369] Key type lgssc unregistered [ 5717.315301] LNet: 125393:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5717.320028] LNetError: 125393:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5717.342562] LNet: Removed LNI 192.168.204.16@tcp [ 5717.987569] Key type .llcrypt unregistered [ 5717.993281] Key type ._llcrypt unregistered [ 5731.555664] Key type ._llcrypt registered [ 5731.560827] Key type .llcrypt registered [ 5732.796557] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 10:00:33 (1782223233) [ 5735.328712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5735.370636] alg: No test for adler32 (adler32-zlib) [ 5736.518820] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 5736.763965] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 5738.440214] Key type lgssc registered [ 5739.684940] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5788.051205] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5792.579631] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5809.398849] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5812.126530] Lustre: Mounted lustre-client [ 5817.329340] Lustre: lustre-MDT0000-mdc-ffff9bf060102800: Connection to lustre-MDT0000 (at 192.168.204.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5833.697198] Lustre: 126366:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782223319/real 1782223319] req@00000000ac94105f x1868796514012288/t0(0) o400->MGC192.168.204.116@tcp@192.168.204.116@tcp:26/25 lens 224/224 e 0 to 1 dl 1782223335 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5833.725376] LustreError: MGC192.168.204.116@tcp: Connection to MGS (at 192.168.204.116@tcp) was lost; in progress operations using this service will fail [ 5842.927407] Lustre: Evicted from MGS (at 192.168.204.116@tcp) after server handle changed from 0xe987fbdcf19ee4fb to 0xe987fbdcf19ee54f [ 5842.941886] Lustre: MGC192.168.204.116@tcp: Connection restored to 192.168.204.116@tcp (at 192.168.204.116@tcp) [ 5849.065816] Lustre: lustre-MDT0000-mdc-ffff9bf060102800: Connection restored to 192.168.204.116@tcp (at 192.168.204.116@tcp) [ 5872.993556] Lustre: Unmounted lustre-client [ 5905.043177] Key type lgssc unregistered [ 5905.292535] LNet: 129402:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5905.308416] LNetError: 129402:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5905.319689] LNet: Removed LNI 192.168.204.16@tcp [ 5906.109395] Key type .llcrypt unregistered [ 5906.112856] Key type ._llcrypt unregistered [ 5921.282270] Key type ._llcrypt registered [ 5921.285216] Key type .llcrypt registered [ 5922.550208] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 10:03:43 (1782223423) [ 5924.647819] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5924.675036] alg: No test for adler32 (adler32-zlib) [ 5925.781813] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 5926.010234] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 5927.728278] Key type lgssc registered [ 5928.932804] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5980.559335] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5985.928683] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6005.711497] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6007.559955] Lustre: Mounted lustre-client [ 6025.283398] Lustre: Unmounted lustre-client [ 6025.848820] Lustre: Mounted lustre-client [ 6035.325261] Lustre: Unmounted lustre-client [ 6065.895161] Key type lgssc unregistered [ 6066.290675] LNet: 133597:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6066.308502] LNetError: 133597:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6066.337710] LNet: Removed LNI 192.168.204.16@tcp [ 6067.388686] Key type .llcrypt unregistered [ 6067.391723] Key type ._llcrypt unregistered [ 6078.682537] Key type ._llcrypt registered [ 6078.685400] Key type .llcrypt registered [ 6080.217117] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 10:06:20 (1782223580) [ 6082.817745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6082.994481] alg: No test for adler32 (adler32-zlib) [ 6084.093726] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 6084.447424] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 6086.240900] Key type lgssc registered [ 6087.531142] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6138.975869] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6145.097966] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6169.145840] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6171.870955] Lustre: Mounted lustre-client [ 6214.702462] Lustre: Unmounted lustre-client [ 6242.064367] Key type lgssc unregistered [ 6242.369597] LNet: 136965:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6242.381034] LNetError: 136965:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6242.411515] LNet: Removed LNI 192.168.204.16@tcp [ 6243.226358] Key type .llcrypt unregistered [ 6243.229755] Key type ._llcrypt unregistered [ 6258.066263] Key type ._llcrypt registered [ 6258.068421] Key type .llcrypt registered [ 6259.380858] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 10:09:20 (1782223760) [ 6261.909256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6262.115258] alg: No test for adler32 (adler32-zlib) [ 6263.218336] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 6263.497610] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 6265.194172] Key type lgssc registered [ 6266.422665] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6316.378939] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6321.987858] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6342.918091] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6345.667873] Lustre: Mounted lustre-client [ 6348.361321] Lustre: Unmounted lustre-client [ 6348.822881] Lustre: Mounted lustre-client [ 6357.036747] Lustre: Unmounted lustre-client [ 6386.143931] Key type lgssc unregistered [ 6386.551142] LNet: 140980:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6386.576432] LNetError: 140980:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6386.600847] LNet: Removed LNI 192.168.204.16@tcp [ 6387.510794] Key type .llcrypt unregistered [ 6387.514076] Key type ._llcrypt unregistered [ 6400.382924] Key type ._llcrypt registered [ 6400.389302] Key type .llcrypt registered [ 6401.994194] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 10:11:42 (1782223902) [ 6405.045383] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6405.075649] alg: No test for adler32 (adler32-zlib) [ 6406.283262] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 6406.626838] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 6408.329413] Key type lgssc registered [ 6409.903637] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6466.126275] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6471.881951] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6494.181617] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6497.072592] Lustre: Mounted lustre-client [ 6499.779504] Lustre: Unmounted lustre-client [ 6500.353807] Lustre: Mounted lustre-client [ 6508.662732] Lustre: Unmounted lustre-client [ 6537.562565] Key type lgssc unregistered [ 6537.849743] LNet: 144581:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6537.864277] LNetError: 144581:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6537.882451] LNet: Removed LNI 192.168.204.16@tcp [ 6538.543765] Key type .llcrypt unregistered [ 6538.545412] Key type ._llcrypt unregistered [ 6551.970201] Key type ._llcrypt registered [ 6551.976208] Key type .llcrypt registered [ 6553.541830] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 10:14:13 (1782224053) [ 6556.234116] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6556.323346] alg: No test for adler32 (adler32-zlib) [ 6557.376539] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 6557.611799] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 6559.289253] Key type lgssc registered [ 6560.676886] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6614.352399] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6620.505210] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6641.231467] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6642.890403] Lustre: Mounted lustre-client [ 6667.094957] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6669.264684] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6686.779332] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6691.445323] Lustre: Unmounted lustre-client [ 6691.800600] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6691.930565] Lustre: Mounted lustre-client [ 6704.593807] Lustre: Unmounted lustre-client [ 6744.176780] Key type lgssc unregistered [ 6744.530521] LNet: 148599:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6744.537973] LNetError: 148599:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6744.556118] LNet: Removed LNI 192.168.204.16@tcp [ 6745.339296] Key type .llcrypt unregistered [ 6745.344379] Key type ._llcrypt unregistered [ 6778.680918] Key type ._llcrypt registered [ 6778.684772] Key type .llcrypt registered [ 6780.163484] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 10:18:00 (1782224280) [ 6782.594616] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6782.621467] alg: No test for adler32 (adler32-zlib) [ 6783.956555] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 6784.181437] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 6785.840217] Key type lgssc registered [ 6787.369196] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6841.018461] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6847.092807] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6868.735252] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6871.457620] Lustre: Mounted lustre-client [ 7023.952105] Lustre: Unmounted lustre-client [ 7024.293763] Lustre: Mounted lustre-client [ 7028.348678] Lustre: Unmounted lustre-client [ 7028.740930] Lustre: Mounted lustre-client [ 7029.925749] Lustre: Unmounted lustre-client [ 7066.138175] Key type lgssc unregistered [ 7066.463694] LNet: 153763:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7066.478091] LNetError: 153763:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7066.498989] LNet: Removed LNI 192.168.204.16@tcp [ 7067.312068] Key type .llcrypt unregistered [ 7067.314859] Key type ._llcrypt unregistered [ 7086.102621] Key type ._llcrypt registered [ 7086.104610] Key type .llcrypt registered [ 7087.481194] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 10:23:08 (1782224588) [ 7090.052122] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7090.066560] alg: No test for adler32 (adler32-zlib) [ 7091.037679] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 7091.242758] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 7092.912169] Key type lgssc registered [ 7094.130608] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7145.257225] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7151.453454] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7172.954544] Lustre: DEBUG MARKER: oleg416-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7174.656554] Lustre: Mounted lustre-client [ 7185.707535] Lustre: Unmounted lustre-client [ 7186.171936] Lustre: Mounted lustre-client [ 7188.951140] Lustre: Unmounted lustre-client [ 7216.924663] Key type lgssc unregistered [ 7217.212260] LNet: 157468:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7217.222313] LNetError: 157468:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7217.246145] LNet: Removed LNI 192.168.204.16@tcp [ 7218.206290] Key type .llcrypt unregistered [ 7218.211621] Key type ._llcrypt unregistered [ 7236.901064] Key type ._llcrypt registered [ 7236.907273] Key type .llcrypt registered [ 7238.333157] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 10:25:38 (1782224738) [ 7239.348591] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7239.454379] alg: No test for adler32 (adler32-zlib) [ 7240.613750] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 7240.915475] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 7242.680267] Key type lgssc registered [ 7243.883823] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7266.340329] LustreError: 159163:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 7276.512670] LustreError: 159172:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 7318.753219] LNet: 1 local NIs in recovery (showing 1): 192.168.204.16@tcp [ 7390.432325] LNet: 1 local NIs in recovery (showing 1): 192.168.204.16@tcp [ 7426.016814] 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 [ 7426.037476] Lustre: Unmounted lustre-client [ 7426.057926] LustreError: 159163:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 7438.498257] Key type lgssc unregistered [ 7446.880500] LNet: 159581:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7446.895156] LNetError: 159581:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7521.706888] LNet: Removed LNI 192.168.204.16@tcp [ 7522.537627] Key type .llcrypt unregistered [ 7522.541912] Key type ._llcrypt unregistered [ 7562.618434] Key type ._llcrypt registered [ 7562.619993] Key type .llcrypt registered [ 7563.068303] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7563.088602] alg: No test for adler32 (adler32-zlib) [ 7564.212563] Lustre: Lustre: Build Version: 2.17.54_83_g7183f09 [ 7564.486439] LNet: Added LNI 192.168.204.16@tcp [8/256/0/180] [ 7566.216216] Key type lgssc registered [ 7567.432252] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7614.494629] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7328 sec ========== 10:31:55 (1782225115) [ 7616.567192] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 10:31:56 (1782225116) === [ 7620.497271] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 10:32:00 (1782225120) === [ 7653.235350] Key type lgssc unregistered [ 7653.605948] LNet: 163818:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7653.624213] LNetError: 163818:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7653.641373] LNet: Removed LNI 192.168.204.16@tcp [ 7654.470852] Key type .llcrypt unregistered [ 7654.472174] Key type ._llcrypt unregistered