[ 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 432705596 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.002439] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004013] kvm-guest: setup PV IPIs [ 0.008688] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010000] pid_max: default: 32768 minimum: 301 [ 0.010132] LSM: Security Framework initializing [ 0.011051] Yama: becoming mindful. [ 0.012037] SELinux: Initializing. [ 0.013169] *** VALIDATE selinux *** [ 0.022905] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.029669] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030204] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032029] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033106] *** VALIDATE tmpfs *** [ 0.034688] *** VALIDATE proc *** [ 0.036197] *** VALIDATE cgroup *** [ 0.037016] *** VALIDATE cgroup2 *** [ 0.038597] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039172] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041032] Spectre V2 : User space: Vulnerable [ 0.042010] Speculative Store Bypass: Vulnerable [ 0.046046] debug: unmapping init [mem 0xffffffff93459000-0xffffffff93460fff] [ 0.048498] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049822] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.050025] ... version: 2 [ 0.051011] ... bit width: 48 [ 0.052012] ... generic registers: 4 [ 0.053010] ... value mask: 0000ffffffffffff [ 0.054012] ... max period: 00007fffffffffff [ 0.055013] ... fixed-purpose events: 3 [ 0.056009] ... event mask: 000000070000000f [ 0.057316] rcu: Hierarchical SRCU implementation. [ 0.060118] smp: Bringing up secondary CPUs ... [ 0.062525] x86: Booting SMP configuration: [ 0.063024] .... node #0, CPUs: #1 #2 #3 [ 0.078729] smp: Brought up 1 node, 4 CPUs [ 0.080012] smpboot: Max logical packages: 1 [ 0.081010] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.139047] node 0 deferred pages initialised in 56ms [ 0.146182] devtmpfs: initialized [ 0.147219] x86/mm: Memory block size: 128MB [ 0.150375] gcov: version magic: 0x41383552 [ 0.152297] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.153076] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.154240] pinctrl core: initialized pinctrl subsystem [ 0.155413] [ 0.155949] ************************************************************* [ 0.156012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.157010] ** ** [ 0.158013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.159010] ** ** [ 0.160015] ** This means that this kernel is built to expose internal ** [ 0.161014] ** IOMMU data structures, which may compromise security on ** [ 0.162012] ** your system. ** [ 0.163011] ** ** [ 0.164017] ** If you see this message and you are not debugging the ** [ 0.165010] ** kernel, report this immediately to your vendor! ** [ 0.166011] ** ** [ 0.167014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.168505] ************************************************************* [ 0.169886] NET: Registered protocol family 16 [ 0.170519] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.171055] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.172054] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.178016] cpuidle: using governor menu [ 0.201032] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.204396] PCI: Using configuration type 1 for base access [ 0.208065] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.228047] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.230011] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.233294] cryptd: max_cpu_qlen set to 1000 [ 0.238311] ACPI: Added _OSI(Module Device) [ 0.244017] ACPI: Added _OSI(Processor Device) [ 0.246099] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.248018] ACPI: Added _OSI(Processor Aggregator Device) [ 0.257000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.271317] ACPI: Interpreter enabled [ 0.274054] ACPI: PM: (supports S0 S3 S4 S5) [ 0.277022] ACPI: Using IOAPIC for interrupt routing [ 0.281170] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.282697] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.299192] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.304043] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.308019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.317078] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.327864] acpiphp: Slot [2] registered [ 0.330321] acpiphp: Slot [5] registered [ 0.334142] acpiphp: Slot [6] registered [ 0.336137] acpiphp: Slot [3] registered [ 0.337000] acpiphp: Slot [4] registered [ 0.342129] acpiphp: Slot [7] registered [ 0.344453] acpiphp: Slot [8] registered [ 0.348192] acpiphp: Slot [9] registered [ 0.355332] acpiphp: Slot [10] registered [ 0.359271] acpiphp: Slot [11] registered [ 0.361155] acpiphp: Slot [12] registered [ 0.362000] acpiphp: Slot [13] registered [ 0.363172] acpiphp: Slot [14] registered [ 0.365639] acpiphp: Slot [15] registered [ 0.368138] acpiphp: Slot [16] registered [ 0.370196] acpiphp: Slot [17] registered [ 0.374172] acpiphp: Slot [18] registered [ 0.378255] acpiphp: Slot [19] registered [ 0.381150] acpiphp: Slot [20] registered [ 0.384159] acpiphp: Slot [21] registered [ 0.389481] acpiphp: Slot [22] registered [ 0.398069] acpiphp: Slot [23] registered [ 0.399000] acpiphp: Slot [24] registered [ 0.402864] acpiphp: Slot [25] registered [ 0.403152] acpiphp: Slot [26] registered [ 0.406151] acpiphp: Slot [27] registered [ 0.408435] acpiphp: Slot [28] registered [ 0.411116] acpiphp: Slot [29] registered [ 0.414396] acpiphp: Slot [30] registered [ 0.417131] acpiphp: Slot [31] registered [ 0.418000] PCI host bridge to bus 0000:00 [ 0.419205] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.424071] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.434033] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.440047] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.444028] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.452026] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.457243] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.460079] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.467000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.478013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.489062] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.493123] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.494000] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.504025] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.509867] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.516621] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.525059] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.533089] pci 0000:00:01.3: quirk_piix4_acpi+0x0/0x1e0 took 16601 usecs [ 0.538198] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.553015] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.571025] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.580774] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.588291] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.604019] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.613032] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.632016] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.653565] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.670021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.687022] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.713019] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.736875] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.745289] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.749434] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.758766] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.763264] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.771069] iommu: Default domain type: Passthrough [ 0.772000] SCSI subsystem initialized [ 0.772138] ACPI: bus type USB registered [ 0.778644] usbcore: registered new interface driver usbfs [ 0.783181] usbcore: registered new interface driver hub [ 0.786083] usbcore: registered new device driver usb [ 0.798152] pps_core: LinuxPPS API ver. 1 registered [ 0.803022] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.809053] PTP clock support registered [ 0.815062] EDAC MC: Ver: 3.0.0 [ 0.815786] PCI: Using ACPI for IRQ routing [ 0.817675] NetLabel: Initializing [ 0.820010] NetLabel: domain hash size = 128 [ 0.823060] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.827081] NetLabel: unlabeled traffic allowed by default [ 0.830117] vgaarb: loaded [ 0.832410] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.834016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.841671] clocksource: Switched to clocksource kvm-clock [ 1.095978] VFS: Disk quotas dquot_6.6.0 [ 1.102461] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.112256] *** VALIDATE ramfs *** [ 1.114658] *** VALIDATE hugetlbfs *** [ 1.116894] pnp: PnP ACPI init [ 1.124285] pnp: PnP ACPI: found 6 devices [ 1.195184] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.202957] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.207164] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.212652] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.218680] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 1.230463] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 1.240880] NET: Registered protocol family 2 [ 1.256250] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 1.275337] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 1.289896] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 1.300417] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 1.303078] TCP: Hash tables configured (established 65536 bind 65536) [ 1.307542] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 1.312802] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.320400] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.325760] NET: Registered protocol family 1 [ 1.328985] RPC: Registered named UNIX socket transport module. [ 1.331868] RPC: Registered udp transport module. [ 1.333942] RPC: Registered tcp transport module. [ 1.335977] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.339471] NET: Registered protocol family 44 [ 1.341133] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.344776] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.348245] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.351305] PCI: CLS 0 bytes, default 64 [ 1.355334] Unpacking initramfs... [ 5.767737] debug: unmapping init [mem 0xffff94c53cc64000-0xffff94c53ffcffff] [ 5.799197] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 5.807038] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 5.819897] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 8.377492] Initialise system trusted keyrings [ 8.379917] Key type blacklist registered [ 8.385116] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 8.401813] zbud: loaded [ 8.408126] *** VALIDATE nfs *** [ 8.410275] *** VALIDATE nfs4 *** [ 8.413259] pstore: using deflate compression [ 8.419202] Platform Keyring initialized [ 8.809131] NET: Registered protocol family 38 [ 8.812793] Key type asymmetric registered [ 8.816577] Asymmetric key parser 'x509' registered [ 8.820289] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 8.826871] io scheduler mq-deadline registered [ 8.833255] io scheduler kyber registered [ 8.834566] io scheduler bfq registered [ 8.843483] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 8.862810] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 8.873873] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 8.882570] ACPI: Power Button [PWRF] [ 8.903230] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 8.932562] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 8.972852] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 9.032498] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 9.091511] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 9.145357] Non-volatile memory driver v1.3 [ 9.150308] Linux agpgart interface v0.103 [ 9.338430] virtio_blk virtio1: [vda] 139280 512-byte logical blocks (71.3 MB/68.0 MiB) [ 9.360448] vda: detected capacity change from 0 to 71311360 [ 9.445317] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 9.458053] vdb: detected capacity change from 0 to 1073741824 [ 9.493780] libphy: Fixed MDIO Bus: probed [ 9.532080] usbcore: registered new interface driver usbserial_generic [ 9.536302] usbserial: USB Serial support registered for generic [ 9.541522] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 9.554851] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 9.558983] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 9.566248] mousedev: PS/2 mouse device common for all mice [ 9.580183] rtc_cmos 00:05: RTC can wake from S4 [ 9.586388] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 9.592258] rtc_cmos 00:05: registered as rtc0 [ 9.598316] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 9.607631] intel_pstate: CPU model not supported [ 9.614740] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 9.641839] hid: raw HID events driver (C) Jiri Kosina [ 9.651164] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 9.654485] usbcore: registered new interface driver usbhid [ 9.654495] usbhid: USB HID core driver [ 9.654740] drop_monitor: Initializing network drop monitor service [ 9.654912] Initializing XFRM netlink socket [ 9.657256] NET: Registered protocol family 10 [ 9.700101] Segment Routing with IPv6 [ 9.701665] NET: Registered protocol family 17 [ 9.717767] mpls_gso: MPLS GSO support [ 9.750703] RAS: Correctable Errors collector initialized. [ 9.753066] AVX version of gcm_enc/dec engaged. [ 9.758428] AES CTR mode by8 optimization enabled [ 10.148882] sched_clock: Marking stable (10148860461, 0)->(11380592517, -1231732056) [ 10.172958] registered taskstats version 1 [ 10.176460] Loading compiled-in X.509 certificates [ 10.183161] zswap: loaded using pool lzo/zbud [ 10.303289] Key type big_key registered [ 10.346279] Key type encrypted registered [ 10.349357] ima: No TPM chip found, activating TPM-bypass! [ 10.353613] ima: Allocated hash algorithm: sha1 [ 10.355831] ima: No architecture policies found [ 10.357597] evm: Initialising EVM extended attributes: [ 10.359956] evm: security.selinux [ 10.361661] evm: security.ima [ 10.363147] evm: security.capability [ 10.364736] evm: HMAC attrs: 0x1 [ 10.367955] rtc_cmos 00:05: setting system clock to 2026-06-16 04:14:19 UTC (1781583259) [ 10.378284] debug: unmapping init [mem 0xffffffff94403000-0xffffffff945fffff] [ 10.384894] debug: unmapping init [mem 0xffffffff93182000-0xffffffff93458fff] [ 10.395054] Write protecting the kernel read-only data: 28672k [ 10.399216] debug: unmapping init [mem 0xffffffff91803000-0xffffffff919fffff] [ 10.415231] debug: unmapping init [mem 0xffffffff92114000-0xffffffff921fffff] [ 10.512840] 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) [ 10.536671] systemd[1]: Detected virtualization kvm. [ 10.538347] systemd[1]: Detected architecture x86-64. [ 10.539954] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 10.650249] systemd[1]: No hostname configured. [ 10.661116] systemd[1]: Set hostname to . [ 10.663705] random: systemd: uninitialized urandom read (16 bytes read) [ 10.677510] systemd[1]: Initializing machine ID from random generator. [ 10.835295] random: ln: uninitialized urandom read (6 bytes read) [ 11.168912] random: systemd: uninitialized urandom read (16 bytes read) [ 11.179739] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 11.196358] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 11.217225] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Swap. [ OK ] Reached target Local File Systems. [ OK ] Reached target Initrd Root Device. [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... [ OK ] Reached target Paths. [ OK ] Reached target Sockets. Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Timers. [ OK ] Reached target Local Encrypted Volumes. Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 13.109969] device-mapper: uevent: version 1.0.3 [ 13.112789] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 16.106297] virtio_net virtio0 ens2: renamed from eth0 [ 16.467396] scsi host0: ata_piix [ 16.613532] scsi host1: ata_piix [ 16.615178] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 16.618958] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 21.882511] random: crng init done [ 21.886527] random: 7 urandom warning(s) missed due to ratelimiting [ 23.479269] dracut-initqueue[585]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 26.024896] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 29.852818] printk: systemd: 26 output lines suppressed due to ratelimiting [ 31.120262] SELinux: Disabled at runtime. [ 31.309433] 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) [ 31.347675] systemd[1]: Detected virtualization kvm. [ 31.358305] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 33.250949] systemd[1]: initrd-switch-root.service: Succeeded. [ 33.259751] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 33.271384] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 33.276177] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 33.281793] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 33.295170] systemd[1]: Starting Journal Service... Starting Journal Service... [ 33.323433] systemd[1]: Activating swap /dev/disk/by-label/SWAP... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting POSIX Message Queue File System... [ OK ] Created slice User and Session Slice. [ 33.391305] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on initctl Compatibility Named Pipe. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice system-getty.slice. [ OK ] Reached target Slices. Mounting Huge Pages File System... [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Stopped target Switch Root. Starting Apply Kernel Variables... [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. Starting Remount Root and Kernel File Systems... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Mounting Kernel Debug File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on Process Core Dump Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 34.917635] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 35.758913] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 35.943437] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 36.305312] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 36.414033] EDAC sbridge: Ver: 1.1.2 [ 40.487921] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit)[ 41.161205] NFS: Registering the id_resolver key type [ 41.163908] Key type id_resolver registered [ 41.165681] Key type id_legacy registered [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... 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 Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Restore /run/initramfs on shutdown... Starting Network Manager... [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... [ OK ] Started OpenSSH server daemon. [ 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 Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg611-client login: [ 57.281012] hrtimer: interrupt took 4001707 ns [ 122.848323] libcfs: loading out-of-tree module taints kernel. [ 123.100051] Key type ._llcrypt registered [ 123.103618] Key type .llcrypt registered [ 124.109332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 124.132581] alg: No test for adler32 (adler32-zlib) [ 126.110979] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 127.526996] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 129.711316] Key type lgssc registered [ 132.599096] Lustre: Echo OBD driver; http://www.lustre.org/ [ 359.685209] Lustre: Mounted lustre-client [ 366.531677] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 383.125446] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing check_logdir /tmp/testlogs/ [ 385.503256] Lustre: lustre-OST0000-osc-ffff94c58b1f4000: disconnect after 23s idle [ 389.594687] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing yml_node [ 395.833685] Lustre: DEBUG MARKER: Client: 2.17.54.40 [ 399.136956] Lustre: DEBUG MARKER: MDS: 2.17.54.40 [ 402.853039] Lustre: DEBUG MARKER: OSS: 2.17.54.40 [ 404.899904] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Jun 16 00:20:52 EDT 2026 [ 425.119374] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 426.987507] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 430.834482] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 441.172080] Lustre: Unmounted lustre-client [ 593.942825] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 600.460725] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 630.584257] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 676.257216] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 00:25:23 (1781583923) [ 679.268798] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 681.403458] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 00:25:28 (1781583928) [ 725.081754] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 732.398848] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 757.560464] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 763.463953] Lustre: Mounted lustre-client [ 768.548360] Lustre: Unmounted lustre-client [ 808.005342] Key type lgssc unregistered [ 808.307903] LNet: 15009:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 808.318520] LNetError: 15009:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 808.345721] LNet: Removed LNI 192.168.206.11@tcp [ 809.347336] Key type .llcrypt unregistered [ 809.359705] Key type ._llcrypt unregistered [ 830.046094] Key type ._llcrypt registered [ 830.049457] Key type .llcrypt registered [ 832.037956] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 00:27:58 (1781584078) [ 834.016924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 834.058988] alg: No test for adler32 (adler32-zlib) [ 835.455633] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 835.797416] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 837.551291] Key type lgssc registered [ 839.546333] Lustre: Echo OBD driver; http://www.lustre.org/ [ 931.356299] Lustre: Mounted lustre-client [ 1020.161566] Lustre: Unmounted lustre-client [ 1059.634176] Key type lgssc unregistered [ 1059.913120] LNet: 18797:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1059.925348] LNetError: 18797:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1059.957727] LNet: Removed LNI 192.168.206.11@tcp [ 1060.867336] Key type .llcrypt unregistered [ 1060.871411] Key type ._llcrypt unregistered [ 1072.905986] Key type ._llcrypt registered [ 1072.912330] Key type .llcrypt registered [ 1074.502845] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 00:32:01 (1781584321) [ 1077.590118] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1077.627777] alg: No test for adler32 (adler32-zlib) [ 1078.969755] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 1079.149979] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 1080.807205] Key type lgssc registered [ 1082.251674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1143.381400] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1149.605440] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1174.814787] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1177.654717] Lustre: Mounted lustre-client [ 1188.407714] Lustre: Unmounted lustre-client [ 1217.819520] Key type lgssc unregistered [ 1218.137568] LNet: 22377:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1218.151591] LNetError: 22377:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1218.175177] LNet: Removed LNI 192.168.206.11@tcp [ 1218.921979] Key type .llcrypt unregistered [ 1218.926425] Key type ._llcrypt unregistered [ 1232.008466] Key type ._llcrypt registered [ 1232.020831] Key type .llcrypt registered [ 1233.492636] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 00:34:40 (1781584480) [ 1236.513769] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1236.674789] alg: No test for adler32 (adler32-zlib) [ 1237.937331] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 1238.202356] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 1239.895440] Key type lgssc registered [ 1241.493325] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1310.599941] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1318.850385] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1344.994689] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1346.828257] Lustre: Mounted lustre-client [ 1353.455487] Lustre: Unmounted lustre-client [ 1387.764936] Key type lgssc unregistered [ 1388.240947] LNet: 25548:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1388.252045] LNetError: 25548:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1388.290258] LNet: Removed LNI 192.168.206.11@tcp [ 1389.573541] Key type .llcrypt unregistered [ 1389.577734] Key type ._llcrypt unregistered [ 1409.157861] Key type ._llcrypt registered [ 1409.163322] Key type .llcrypt registered [ 1410.621054] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 00:37:38 (1781584658) [ 1413.473098] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1413.522421] alg: No test for adler32 (adler32-zlib) [ 1414.673301] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 1414.896903] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 1416.639697] Key type lgssc registered [ 1418.382608] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1474.754506] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1483.562406] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1509.939122] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1521.248543] Lustre: Mounted lustre-client [ 1526.260646] Lustre: lustre-MDT0000-mdc-ffff94c589847800: Connection to lustre-MDT0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1536.493029] Lustre: lustre-MDT0001-mdc-ffff94c589847800: Connection to lustre-MDT0001 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1541.640184] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1547.743328] Lustre: 26507:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781584780/real 1781584780] req@00000000b809fc05 x1868126920644608/t0(0) o400->MGC192.168.206.111@tcp@192.168.206.111@tcp:26/25 lens 224/224 e 0 to 1 dl 1781584796 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1547.771170] LustreError: MGC192.168.206.111@tcp: Connection to MGS (at 192.168.206.111@tcp) was lost; in progress operations using this service will fail [ 1552.029813] Lustre: Unmounted lustre-client [ 1582.245900] Key type lgssc unregistered [ 1582.546651] LNet: 29183:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1582.555368] LNetError: 29183:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1583.592811] LNet: Removed LNI 192.168.206.11@tcp [ 1584.545830] Key type .llcrypt unregistered [ 1584.552138] Key type ._llcrypt unregistered [ 1602.447901] Key type ._llcrypt registered [ 1602.451498] Key type .llcrypt registered [ 1604.088752] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 00:40:51 (1781584851) [ 1606.377261] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1606.526699] alg: No test for adler32 (adler32-zlib) [ 1607.771208] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 1608.224739] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 1609.967496] Key type lgssc registered [ 1611.533441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1667.587457] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1674.238533] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1700.098445] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1702.999204] Lustre: Mounted lustre-client [ 1708.008744] Lustre: lustre-OST0000-osc-ffff94c5880b7800: Connection to lustre-OST0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1715.853313] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1726.215289] Lustre: Unmounted lustre-client [ 1756.924943] Key type lgssc unregistered [ 1757.241069] LNet: 32776:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1757.246121] LNetError: 32776:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1758.309873] LNet: Removed LNI 192.168.206.11@tcp [ 1759.171435] Key type .llcrypt unregistered [ 1759.173762] Key type ._llcrypt unregistered [ 1776.701036] Key type ._llcrypt registered [ 1776.706501] Key type .llcrypt registered [ 1778.580656] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 00:43:45 (1781585025) [ 1781.134242] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1781.250456] alg: No test for adler32 (adler32-zlib) [ 1782.336639] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 1782.606583] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 1784.327210] Key type lgssc registered [ 1785.938687] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1846.171571] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1853.483622] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1879.069466] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1886.280620] Lustre: Mounted lustre-client [ 1890.472113] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1894.994296] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1906.865492] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-06-16 0h45m54s [ 1909.103566] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1911.787067] Lustre: lustre-MDT0000-mdc-ffff94c58673f800: Connection to lustre-MDT0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1919.757023] LustreError: 36546:0:(lmv_obd.c:1466:lmv_statfs()) lustre-MDT0000-mdc-ffff94c58673f800: can't stat MDS #0: rc = -19 [ 1921.284240] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1929.190566] LustreError: MGC192.168.206.111@tcp: Connection to MGS (at 192.168.206.111@tcp) was lost; in progress operations using this service will fail [ 1929.211777] Lustre: Evicted from MGS (at 192.168.206.111@tcp) after server handle changed from 0xdc852d73e96ea8df to 0xdc852d73e96eac82 [ 1929.227707] Lustre: MGC192.168.206.111@tcp: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [ 1930.283891] LustreError: lustre-MDT0000-mdc-ffff94c58673f800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1930.317208] Lustre: lustre-MDT0000-mdc-ffff94c58673f800: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [ 1935.893768] Lustre: DEBUG MARKER: Wait for df (36546) ... [ 1937.529133] Lustre: DEBUG MARKER: done [ 1940.343195] Lustre: Unmounted lustre-client [ 1970.313615] Key type lgssc unregistered [ 1970.650950] LNet: 37562:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1970.662374] LNetError: 37562:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1970.680891] LNet: Removed LNI 192.168.206.11@tcp [ 1971.402423] Key type .llcrypt unregistered [ 1971.405904] Key type ._llcrypt unregistered [ 2001.735658] Key type ._llcrypt registered [ 2001.738266] Key type .llcrypt registered [ 2003.217177] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 00:47:30 (1781585250) [ 2006.531873] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2006.573445] alg: No test for adler32 (adler32-zlib) [ 2007.862069] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 2008.118409] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 2009.839801] Key type lgssc registered [ 2011.260900] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2062.424060] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2068.703789] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2091.009349] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2093.620853] Lustre: Mounted lustre-client [ 2098.264700] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-06-16 0h49m05s [ 2100.205415] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 2108.600736] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 2111.388600] Lustre: DEBUG MARKER: Creating a test file and stat it [ 2131.935205] Lustre: 41685:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781585360/real 1781585360] req@00000000fa95d2de x1868127542450944/t0(0) o101->lustre-MDT0000-mdc-ffff94c590aba800@192.168.206.111@tcp:12/10 lens 576/1152 e 0 to 1 dl 1781585380 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 2131.995873] Lustre: lustre-MDT0000-mdc-ffff94c590aba800: Connection to lustre-MDT0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2132.056739] Lustre: lustre-MDT0000-mdc-ffff94c590aba800: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [ 2153.738843] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 2157.434975] Lustre: DEBUG MARKER: done [ 2162.446442] Lustre: Unmounted lustre-client [ 2190.994563] Key type lgssc unregistered [ 2191.282939] LNet: 42572:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2191.290939] LNetError: 42572:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2191.326179] LNet: Removed LNI 192.168.206.11@tcp [ 2191.943565] Key type .llcrypt unregistered [ 2191.946112] Key type ._llcrypt unregistered [ 2221.635157] Key type ._llcrypt registered [ 2221.638573] Key type .llcrypt registered [ 2223.124663] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 00:51:10 (1781585470) [ 2224.709674] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2226.409945] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 00:51:14 (1781585474) [ 2230.937746] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2230.994473] alg: No test for adler32 (adler32-zlib) [ 2232.242555] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 2232.627480] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 2234.415226] Key type lgssc registered [ 2235.989918] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2267.903786] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 00:51:55 (1781585515) [ 2304.648490] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2310.117473] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2331.574497] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2334.323516] Lustre: Mounted lustre-client [ 2338.266810] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2342.421306] Lustre: Unmounted lustre-client [ 2359.884056] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2410.519332] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2416.579868] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2417.417144] Lustre: Mounted lustre-client [ 2422.061536] Lustre: Unmounted lustre-client [ 2490.007654] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2496.002185] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2496.786345] Lustre: Mounted lustre-client [ 2500.852973] Lustre: Unmounted lustre-client [ 2518.455251] Lustre: DEBUG MARKER: files compared the same [ 2531.939937] Key type lgssc unregistered [ 2532.189633] LNet: 50147:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2532.201401] LNetError: 50147:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2532.230826] LNet: Removed LNI 192.168.206.11@tcp [ 2532.843986] Key type .llcrypt unregistered [ 2532.851837] Key type ._llcrypt unregistered [ 2549.525953] Key type ._llcrypt registered [ 2549.529856] Key type .llcrypt registered [ 2551.211893] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 00:56:38 (1781585798) [ 2553.766962] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2553.848667] alg: No test for adler32 (adler32-zlib) [ 2555.189740] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 2555.530285] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 2557.263554] Key type lgssc registered [ 2558.781435] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2614.287556] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2620.630349] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2643.493354] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2645.041153] Lustre: Mounted lustre-client [ 2649.392955] Lustre: Unmounted lustre-client [ 2677.639508] Key type lgssc unregistered [ 2677.950031] LNet: 53708:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2677.961681] LNetError: 53708:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2677.994739] LNet: Removed LNI 192.168.206.11@tcp [ 2678.986385] Key type .llcrypt unregistered [ 2678.990232] Key type ._llcrypt unregistered [ 2697.546230] Key type ._llcrypt registered [ 2697.547995] Key type .llcrypt registered [ 2699.046860] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 00:59:06 (1781585946) [ 2701.525052] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2701.576506] alg: No test for adler32 (adler32-zlib) [ 2702.651228] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 2702.932902] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 2704.703216] Key type lgssc registered [ 2706.084826] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2792.174242] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2832.830364] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2839.445141] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2872.658457] Key type lgssc unregistered [ 2873.054901] LNet: 57189:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2873.085469] LNetError: 57189:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2873.108051] LNet: Removed LNI 192.168.206.11@tcp [ 2874.369505] Key type .llcrypt unregistered [ 2874.372847] Key type ._llcrypt unregistered [ 2892.641208] Key type ._llcrypt registered [ 2892.643016] Key type .llcrypt registered [ 2894.058842] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 01:02:21 (1781586141) [ 2898.025053] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2898.070664] alg: No test for adler32 (adler32-zlib) [ 2899.185754] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 2899.564710] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 2901.279963] Key type lgssc registered [ 2902.980553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2984.575200] Lustre: Mounted lustre-client [ 2992.684941] Lustre: Unmounted lustre-client [ 3034.622223] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 01:04:42 (1781586282) [ 3171.092752] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3193.406814] Lustre: Mounted lustre-client [ 3200.775345] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3200.791320] LustreError: 63675:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff94c590ffa000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3200.827228] Lustre: 58175:0:(llite_lib.c:4198:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.206.111@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3211.180282] Lustre: Unmounted lustre-client [ 3266.977161] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 01:08:34 (1781586514) [ 3280.140218] Key type lgssc unregistered [ 3280.454425] LNet: 64889:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3280.464636] LNetError: 64889:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3281.509792] LNet: Removed LNI 192.168.206.11@tcp [ 3282.645748] Key type .llcrypt unregistered [ 3282.651251] Key type ._llcrypt unregistered [ 3296.819521] Key type ._llcrypt registered [ 3296.823525] Key type .llcrypt registered [ 3297.426467] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3297.478863] alg: No test for adler32 (adler32-zlib) [ 3298.674699] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 3298.974231] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 3300.655347] Key type lgssc registered [ 3302.426731] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3362.848266] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3370.459099] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3396.679945] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3402.824098] Lustre: Mounted lustre-client [ 3407.331672] Lustre: Unmounted lustre-client [ 3438.779265] Key type lgssc unregistered [ 3439.176385] LNet: 67878:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3439.191461] LNetError: 67878:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3439.217630] LNet: Removed LNI 192.168.206.11@tcp [ 3440.169265] Key type .llcrypt unregistered [ 3440.175057] Key type ._llcrypt unregistered [ 3457.037492] Key type ._llcrypt registered [ 3457.042066] Key type .llcrypt registered [ 3457.841325] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3457.873483] alg: No test for adler32 (adler32-zlib) [ 3459.182908] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 3459.606274] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 3461.359477] Key type lgssc registered [ 3463.443743] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3602.823520] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3611.425483] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3642.070442] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3644.161051] Lustre: Mounted lustre-client [ 3646.832766] Lustre: Unmounted lustre-client [ 3675.771522] Key type lgssc unregistered [ 3676.014876] LNet: 71291:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3676.022485] LNetError: 71291:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3676.051623] LNet: Removed LNI 192.168.206.11@tcp [ 3676.990219] Key type .llcrypt unregistered [ 3676.993721] Key type ._llcrypt unregistered [ 3696.574082] Key type ._llcrypt registered [ 3696.576763] Key type .llcrypt registered [ 3698.470413] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 01:15:45 (1781586945) [ 3701.338829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3701.360305] alg: No test for adler32 (adler32-zlib) [ 3702.609361] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 3703.049424] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 3704.839264] Key type lgssc registered [ 3706.371157] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3764.925880] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3772.524046] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3801.778226] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3809.364268] Lustre: Mounted lustre-client [ 3817.724322] Lustre: Unmounted lustre-client [ 3818.189981] Lustre: Mounted lustre-client [ 3821.947996] Lustre: Unmounted lustre-client [ 3862.642910] Key type lgssc unregistered [ 3863.112346] LNet: 74962:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3863.120709] LNetError: 74962:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3863.151899] LNet: Removed LNI 192.168.206.11@tcp [ 3864.198885] Key type .llcrypt unregistered [ 3864.203454] Key type ._llcrypt unregistered [ 3881.430536] Key type ._llcrypt registered [ 3881.448798] Key type .llcrypt registered [ 3882.318290] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3882.337361] alg: No test for adler32 (adler32-zlib) [ 3883.586260] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 3883.925134] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 3885.743451] Key type lgssc registered [ 3887.520395] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3961.704918] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3969.189761] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3999.524542] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4001.296226] Lustre: Mounted lustre-client [ 4007.603346] Lustre: Unmounted lustre-client [ 4040.507265] Key type lgssc unregistered [ 4040.874260] LNet: 77976:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4040.891635] LNetError: 77976:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4040.915242] LNet: Removed LNI 192.168.206.11@tcp [ 4041.882798] Key type .llcrypt unregistered [ 4041.886885] Key type ._llcrypt unregistered [ 4062.948912] Key type ._llcrypt registered [ 4062.951877] Key type .llcrypt registered [ 4064.558864] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 01:21:51 (1781587311) [ 4067.414659] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4067.438756] alg: No test for adler32 (adler32-zlib) [ 4068.718223] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 4069.087194] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 4070.839439] Key type lgssc registered [ 4072.708671] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4138.504696] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4146.370189] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4174.924398] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4176.634496] Lustre: Mounted lustre-client [ 4183.039231] Lustre: lustre: root_squash is set to 0:0 [ 4192.805067] Lustre: lustre: nosquash_nids is cleared [ 4200.020157] Lustre: lustre: root_squash is set to 500:500 [ 4215.368334] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.206.11@tcp 192.168.0.[2,10]@tcp [ 4223.744653] Lustre: lustre: nosquash_nids set to 192.168.206.11/24@tcp [ 4234.964204] Lustre: Unmounted lustre-client [ 4266.925416] Key type lgssc unregistered [ 4267.283563] LNet: 81939:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4267.311732] LNetError: 81939:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4268.331439] LNet: Removed LNI 192.168.206.11@tcp [ 4269.329777] Key type .llcrypt unregistered [ 4269.335670] Key type ._llcrypt unregistered [ 4292.322491] Key type ._llcrypt registered [ 4292.328170] Key type .llcrypt registered [ 4294.322708] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 01:25:41 (1781587541) [ 4297.151420] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 4299.081689] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 01:25:46 (1781587546) [ 4302.772598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4302.827960] alg: No test for adler32 (adler32-zlib) [ 4303.992063] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 4304.428884] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 4306.192393] Key type lgssc registered [ 4307.589599] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4367.283328] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4373.294438] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4397.777727] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4399.463756] Lustre: lustre: root_squash is set to 500:500 [ 4399.506836] Lustre: lustre: nosquash_nids set to 192.168.206.11/24@tcp [ 4400.662486] Lustre: Mounted lustre-client [ 4407.098655] Lustre: Unmounted lustre-client [ 4448.950984] Key type lgssc unregistered [ 4449.398710] LNet: 85886:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4449.424306] LNetError: 85886:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4449.449472] LNet: Removed LNI 192.168.206.11@tcp [ 4450.333550] Key type .llcrypt unregistered [ 4450.336395] Key type ._llcrypt unregistered [ 4469.510824] Key type ._llcrypt registered [ 4469.516423] Key type .llcrypt registered [ 4471.159515] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4473.216690] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 01:28:40 (1781587720) [ 4494.053839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4494.084420] alg: No test for adler32 (adler32-zlib) [ 4495.205165] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 4495.505911] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 4497.271293] Key type lgssc registered [ 4498.721326] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4598.048362] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4604.978726] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4634.448526] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4719.930387] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4728.016878] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4756.597406] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4770.488341] Lustre: Mounted lustre-client [ 4811.276816] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff94c585e33800.ost_server_uuid 50 [ 4813.571757] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff94c585e33800.ost_server_uuid in IDLE state after 0 sec [ 4814.287461] Lustre: Mounted lustre-client [ 4819.582379] Lustre: Unmounted lustre-client [ 4823.235425] Lustre: Unmounted lustre-client [ 4873.767945] Key type lgssc unregistered [ 4874.065947] LNet: 93513:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4874.077583] LNetError: 93513:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4874.099371] LNet: Removed LNI 192.168.206.11@tcp [ 4874.866185] Key type .llcrypt unregistered [ 4874.870215] Key type ._llcrypt unregistered [ 4910.841241] Key type ._llcrypt registered [ 4910.844237] Key type .llcrypt registered [ 4912.514425] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 01:35:59 (1781588159) [ 4932.293316] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4932.338435] alg: No test for adler32 (adler32-zlib) [ 4933.661959] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 4934.049382] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 4935.777794] Key type lgssc registered [ 4937.178074] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5037.939573] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5045.523904] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5075.172291] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5076.835811] Lustre: Mounted lustre-client [ 5098.463280] Lustre: 94878:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781588331/real 1781588331] req@000000000e735231 x1868130610582656/t0(0) o400->lustre-OST0000-osc-ffff94c586b4a800@192.168.206.111@tcp:28/4 lens 224/224 e 0 to 1 dl 1781588347 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5098.509210] Lustre: lustre-OST0000-osc-ffff94c586b4a800: Connection to lustre-OST0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5103.583204] Lustre: 94877:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781588336/real 1781588336] req@00000000b2cafb94 x1868130610583168/t0(0) o400->lustre-OST0000-osc-ffff94c586b4a800@192.168.206.111@tcp:28/4 lens 224/224 e 0 to 1 dl 1781588352 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5108.704224] Lustre: 94878:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781588341/real 1781588341] req@00000000b37f56e9 x1868130610583680/t0(0) o400->lustre-OST0000-osc-ffff94c586b4a800@192.168.206.111@tcp:28/4 lens 224/224 e 0 to 1 dl 1781588357 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5108.802331] Lustre: lustre-OST0000-osc-ffff94c586b4a800: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [ 5113.833184] Lustre: lustre-MDT0000-mdc-ffff94c586b4a800: Connection to lustre-MDT0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5113.866736] Lustre: 94878:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781588346/real 1781588346] req@0000000069480ab6 x1868130610584192/t0(0) o400->lustre-OST0000-osc-ffff94c586b4a800@192.168.206.111@tcp:28/4 lens 224/224 e 0 to 1 dl 1781588362 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5129.119539] Lustre: 94877:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781588362/real 1781588362] req@000000008f5e3d51 x1868130610586496/t0(0) o400->MGC192.168.206.111@tcp@192.168.206.111@tcp:26/25 lens 224/224 e 0 to 1 dl 1781588378 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5129.143171] LustreError: MGC192.168.206.111@tcp: Connection to MGS (at 192.168.206.111@tcp) was lost; in progress operations using this service will fail [ 5139.453871] Lustre: Evicted from MGS (at 192.168.206.111@tcp) after server handle changed from 0xd8307a94519844da to 0xd8307a9451984670 [ 5139.475653] Lustre: MGC192.168.206.111@tcp: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [ 5148.472116] Lustre: lustre-MDT0000-mdc-ffff94c586b4a800: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [ 5154.913145] Lustre: Unmounted lustre-client [ 5183.670308] Key type lgssc unregistered [ 5183.970653] LNet: 99158:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5183.983361] LNetError: 99158:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5184.012871] LNet: Removed LNI 192.168.206.11@tcp [ 5184.679308] Key type .llcrypt unregistered [ 5184.682784] Key type ._llcrypt unregistered [ 5203.476582] Key type ._llcrypt registered [ 5203.478634] Key type .llcrypt registered [ 5205.120612] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 01:40:52 (1781588452) [ 5221.052375] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5221.120278] alg: No test for adler32 (adler32-zlib) [ 5222.496992] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 5222.857622] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 5224.687261] Key type lgssc registered [ 5226.935444] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5278.017281] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5393.197725] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5400.024843] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5427.219864] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5430.074731] Lustre: Mounted lustre-client [ 5432.998353] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5435.249634] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 01:44:42 (1781588682) [ 5682.159305] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5692.520254] Lustre: Unmounted lustre-client [ 5819.987595] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5827.730847] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5858.224485] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5905.101278] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 01:52:32 (1781589152) [ 6007.333203] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6012.623537] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6035.951598] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6036.502666] Lustre: Mounted lustre-client [ 6040.990600] Lustre: Unmounted lustre-client [ 6073.618810] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 01:55:21 (1781589321) [ 6176.457711] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6182.179698] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6210.319264] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6211.136545] Lustre: Mounted lustre-client [ 6216.146115] Lustre: Unmounted lustre-client [ 6253.691508] Key type lgssc unregistered [ 6254.026580] LNet: 122091:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6254.046420] LNetError: 122091:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6255.078628] LNet: Removed LNI 192.168.206.11@tcp [ 6255.966482] Key type .llcrypt unregistered [ 6255.971964] Key type ._llcrypt unregistered [ 6276.592661] Key type ._llcrypt registered [ 6276.594794] Key type .llcrypt registered [ 6278.029562] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 01:58:45 (1781589525) [ 6281.666631] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6281.689755] alg: No test for adler32 (adler32-zlib) [ 6283.138216] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 6283.533329] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 6285.351221] Key type lgssc registered [ 6286.821184] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6342.493326] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6348.789300] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6373.816701] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6375.770471] Lustre: Mounted lustre-client [ 6390.374248] Lustre: Unmounted lustre-client [ 6422.890542] Key type lgssc unregistered [ 6423.328811] LNet: 125682:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6423.335330] LNetError: 125682:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6424.363864] LNet: Removed LNI 192.168.206.11@tcp [ 6425.493551] Key type .llcrypt unregistered [ 6425.503642] Key type ._llcrypt unregistered [ 6445.111109] Key type ._llcrypt registered [ 6445.116564] Key type .llcrypt registered [ 6447.340978] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 02:01:33 (1781589693) [ 6451.044260] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6451.085426] alg: No test for adler32 (adler32-zlib) [ 6452.293149] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 6452.595469] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 6454.335943] Key type lgssc registered [ 6455.577449] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6513.226311] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6519.633615] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6542.572222] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6545.429843] Lustre: Mounted lustre-client [ 6555.631129] Lustre: lustre-OST0000-osc-ffff94c58d20f000: Connection to lustre-OST0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6570.250226] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff94c58d20f000.ost_server_uuid 50 [ 6573.865241] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6585.212650] Lustre: Unmounted lustre-client [ 6610.144214] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 02:04:17 (1781589857) [ 6649.611139] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6655.934877] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6681.768278] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6708.703729] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6709.495588] Lustre: Mounted lustre-client [ 6714.874556] Lustre: lustre-OST0000-osc-ffff94c58888a800: Connection to lustre-OST0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6742.200954] Lustre: Unmounted lustre-client [ 6794.643837] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 02:07:21 (1781590041) [ 6834.022435] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6840.373636] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6864.656917] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6887.975425] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6888.718663] LustreError: 126643:0:(lmv_obd.c:208:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 6888.774157] Lustre: Mounted lustre-client [ 6894.091429] Lustre: lustre-OST0000-osc-ffff94c5869c8800: Connection to lustre-OST0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6911.350380] Lustre: Unmounted lustre-client [ 6962.177811] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 02:10:09 (1781590209) [ 7069.924219] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7076.740224] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7106.431190] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7185.123811] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7192.537692] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7219.310544] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7262.457211] Lustre: Mounted lustre-client [ 7308.641528] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7310.567142] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7312.941421] Lustre: Unmounted lustre-client [ 7348.060175] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 02:16:35 (1781590595) [ 7386.467981] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7393.593585] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7418.952827] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7457.386698] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7500.503771] Lustre: Mounted lustre-client [ 7548.943805] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7550.698874] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7565.071333] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7575.427926] Lustre: Unmounted lustre-client [ 7634.218938] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 02:21:21 (1781590881) [ 7670.238506] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7676.999633] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7700.399091] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7701.443362] Lustre: Mounted lustre-client [ 7730.061477] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7732.273430] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7749.222754] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff94c590abe800.ost_server_uuid 50 [ 7751.017652] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff94c590abe800.ost_server_uuid in IDLE state after 0 sec [ 7754.921596] Lustre: Unmounted lustre-client [ 7755.413274] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7755.420531] Lustre: Skipped 3 previous similar messages [ 7755.527491] Lustre: Mounted lustre-client [ 7757.016058] LustreError: 148927:0:(sec.c:413:import_sec_validate_get()) import 00000000ee40cfca (NEW) with no sec [ 7829.482953] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 02:24:36 (1781591076) [ 7869.732035] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7876.870892] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7901.485909] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7924.760096] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7925.418274] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7925.572221] Lustre: Mounted lustre-client [ 7940.435600] Lustre: Unmounted lustre-client [ 7940.448489] Lustre: Skipped 1 previous similar message [ 7980.510161] Key type lgssc unregistered [ 7980.809289] LNet: 152312:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7980.820416] LNetError: 152312:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7980.843263] LNet: Removed LNI 192.168.206.11@tcp [ 7981.681438] Key type .llcrypt unregistered [ 7981.684883] Key type ._llcrypt unregistered [ 7998.785432] Key type ._llcrypt registered [ 7998.791441] Key type .llcrypt registered [ 8000.423805] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 02:27:27 (1781591247) [ 8001.380307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8001.471915] alg: No test for adler32 (adler32-zlib) [ 8002.591180] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 8002.847490] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 8004.583882] Key type lgssc registered [ 8006.074754] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8059.926453] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8065.670880] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8091.963876] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8115.743603] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8116.318039] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8117.518133] Lustre: Mounted lustre-client [ 8125.525885] LustreError: lustre-MDT0001-mdc-ffff94c58829d800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 8125.573367] Lustre: lustre-MDT0001-mdc-ffff94c58829d800: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [ 8132.019242] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8133.846446] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8157.727873] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8162.375988] Lustre: Unmounted lustre-client [ 8190.742524] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 02:30:37 (1781591437) [ 8297.080467] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8302.794466] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8325.351340] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8326.220833] Lustre: Mounted lustre-client [ 8355.168805] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8357.022357] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8360.943785] Lustre: lustre-OST0001-osc-ffff94c590ffd000: Connection to lustre-OST0001 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8379.029438] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8389.440039] Lustre: Unmounted lustre-client [ 8432.188518] Key type lgssc unregistered [ 8432.486247] LNet: 161896:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8432.504565] LNetError: 161896:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8432.533795] LNet: Removed LNI 192.168.206.11@tcp [ 8433.378570] Key type .llcrypt unregistered [ 8433.387096] Key type ._llcrypt unregistered [ 8469.593881] Key type ._llcrypt registered [ 8469.597583] Key type .llcrypt registered [ 8471.297522] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 02:35:18 (1781591718) [ 8473.741278] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8473.758324] alg: No test for adler32 (adler32-zlib) [ 8474.926165] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 8475.266524] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 8477.091207] Key type lgssc registered [ 8478.727667] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8535.533337] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8541.895444] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8564.810145] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8566.475735] Lustre: Mounted lustre-client [ 8614.931262] Lustre: Unmounted lustre-client [ 8661.526845] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8662.119275] Lustre: Mounted lustre-client [ 8698.700868] Lustre: Unmounted lustre-client [ 8730.041706] Key type lgssc unregistered [ 8730.372683] LNet: 166321:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8730.380062] LNetError: 166321:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8730.399249] LNet: Removed LNI 192.168.206.11@tcp [ 8731.265310] Key type .llcrypt unregistered [ 8731.267188] Key type ._llcrypt unregistered [ 8748.659489] Key type ._llcrypt registered [ 8748.662723] Key type .llcrypt registered [ 8750.101556] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 02:39:57 (1781591997) [ 8752.810334] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8752.836205] alg: No test for adler32 (adler32-zlib) [ 8754.019756] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 8754.320785] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 8756.079428] Key type lgssc registered [ 8757.702854] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8820.363879] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8826.824857] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8855.446719] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8858.506931] Lustre: Mounted lustre-client [ 8882.845273] Lustre: Unmounted lustre-client [ 8913.810208] Key type lgssc unregistered [ 8914.187368] LNet: 170059:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8914.209599] LNetError: 170059:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8914.245540] LNet: Removed LNI 192.168.206.11@tcp [ 8915.309587] Key type .llcrypt unregistered [ 8915.315268] Key type ._llcrypt unregistered [ 8933.001839] Key type ._llcrypt registered [ 8933.006661] Key type .llcrypt registered [ 8933.715672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8933.739752] alg: No test for adler32 (adler32-zlib) [ 8934.993158] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 8935.313434] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 8937.071916] Key type lgssc registered [ 8938.663823] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8994.745986] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9001.109580] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9024.126371] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9025.866520] Lustre: Mounted lustre-client [ 9037.417559] Lustre: Unmounted lustre-client [ 9077.188300] Key type lgssc unregistered [ 9077.435678] LNet: 173103:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9077.442895] LNetError: 173103:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9078.515707] LNet: Removed LNI 192.168.206.11@tcp [ 9079.486385] Key type .llcrypt unregistered [ 9079.489723] Key type ._llcrypt unregistered [ 9097.943693] Key type ._llcrypt registered [ 9097.947302] Key type .llcrypt registered [ 9099.590613] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 02:45:46 (1781592346) [ 9102.069567] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9102.126410] alg: No test for adler32 (adler32-zlib) [ 9103.331063] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 9103.686237] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 9105.455193] Key type lgssc registered [ 9107.098870] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9168.746575] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9175.276510] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9198.549626] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9201.367978] Lustre: Mounted lustre-client [ 9226.795389] Lustre: Unmounted lustre-client [ 9257.655841] Key type lgssc unregistered [ 9257.941433] LNet: 176834:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9257.951726] LNetError: 176834:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9257.971553] LNet: Removed LNI 192.168.206.11@tcp [ 9258.691706] Key type .llcrypt unregistered [ 9258.694264] Key type ._llcrypt unregistered [ 9273.627518] Key type ._llcrypt registered [ 9273.651568] Key type .llcrypt registered [ 9273.932471] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9273.959349] alg: No test for adler32 (adler32-zlib) [ 9275.200647] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 9275.520976] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 9277.271887] Key type lgssc registered [ 9279.043195] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9337.505763] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9343.756615] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9368.267663] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9370.389933] Lustre: Mounted lustre-client [ 9378.195100] Lustre: Unmounted lustre-client [ 9406.525920] Key type lgssc unregistered [ 9406.916657] LNet: 179874:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9406.929675] LNetError: 179874:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9406.960675] LNet: Removed LNI 192.168.206.11@tcp [ 9407.911068] Key type .llcrypt unregistered [ 9407.913579] Key type ._llcrypt unregistered [ 9426.558367] Key type ._llcrypt registered [ 9426.564179] Key type .llcrypt registered [ 9428.057559] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 02:51:15 (1781592675) [ 9454.091261] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9454.244655] alg: No test for adler32 (adler32-zlib) [ 9455.350869] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 9455.523465] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 9457.255258] Key type lgssc registered [ 9458.691164] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9560.904242] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9566.782916] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9593.500594] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9640.575207] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 02:54:48 (1781592888) [ 9677.603941] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9684.057982] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9707.527388] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9709.302229] Lustre: Mounted lustre-client [ 9718.362633] Lustre: Unmounted lustre-client [ 9751.714573] Key type lgssc unregistered [ 9752.107615] LNet: 186928:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9752.121631] LNetError: 186928:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9752.157798] LNet: Removed LNI 192.168.206.11@tcp [ 9753.087208] Key type .llcrypt unregistered [ 9753.093591] Key type ._llcrypt unregistered [ 9771.671269] Key type ._llcrypt registered [ 9771.676242] Key type .llcrypt registered [ 9773.446865] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9775.512903] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 02:57:02 (1781593022) [ 9795.708054] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9795.751927] alg: No test for adler32 (adler32-zlib) [ 9796.883533] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 9797.252327] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [ 9798.967240] Key type lgssc registered [ 9800.423844] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9902.486574] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9908.513080] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9932.877572] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 9956.086655] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 9959.340505] Lustre: Mounted lustre-client [ 9987.617869] Lustre: Unmounted lustre-client [10119.287977] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10125.592226] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10148.890879] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10200.676817] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 03:04:08 (1781593448) [10203.394140] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [10205.655691] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 03:04:12 (1781593452) [10309.010402] Key type lgssc unregistered [10309.350509] LNet: 198322:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10309.363817] LNetError: 198322:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10309.394902] LNet: Removed LNI 192.168.206.11@tcp [10310.168197] Key type .llcrypt unregistered [10310.175273] Key type ._llcrypt unregistered [10329.914316] Key type ._llcrypt registered [10329.917303] Key type .llcrypt registered [10331.680792] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 03:06:18 (1781593578) [10377.411530] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10377.444337] alg: No test for adler32 (adler32-zlib) [10378.546318] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [10378.814302] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [10380.535347] Key type lgssc registered [10382.151840] Lustre: Echo OBD driver; http://www.lustre.org/ [10436.956605] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10442.435936] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10483.445558] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10526.305484] Key type lgssc unregistered [10526.633566] LNet: 202036:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10526.645231] LNetError: 202036:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10526.678217] LNet: Removed LNI 192.168.206.11@tcp [10527.595741] Key type .llcrypt unregistered [10527.597558] Key type ._llcrypt unregistered [10549.069363] Key type ._llcrypt registered [10549.071469] Key type .llcrypt registered [10551.017072] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 03:09:58 (1781593798) [10555.067805] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10555.185811] alg: No test for adler32 (adler32-zlib) [10556.352495] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [10556.863855] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [10558.719840] Key type lgssc registered [10560.869759] Lustre: Echo OBD driver; http://www.lustre.org/ [10629.992155] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10637.970824] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10669.188168] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10672.059402] Lustre: Mounted lustre-client [10682.348507] Lustre: lustre-MDT0000-mdc-ffff94c591558000: Connection to lustre-MDT0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [10692.590709] Lustre: lustre-MDT0001-mdc-ffff94c591558000: Connection to lustre-MDT0001 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [10703.839199] Lustre: 203029:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781593936/real 1781593936] req@000000005303ebe2 x1868136505753600/t0(0) o400->MGC192.168.206.111@tcp@192.168.206.111@tcp:26/25 lens 224/224 e 0 to 1 dl 1781593952 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10703.893515] LustreError: MGC192.168.206.111@tcp: Connection to MGS (at 192.168.206.111@tcp) was lost; in progress operations using this service will fail [10729.532138] Lustre: Evicted from MGS (at 192.168.206.111@tcp) after server handle changed from 0x4ab200d59b326f00 to 0x4ab200d59b32883d [10729.561642] Lustre: MGC192.168.206.111@tcp: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [10762.749080] LustreError: lustre-MDT0000-mdc-ffff94c591558000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10762.836124] Lustre: lustre-MDT0000-mdc-ffff94c591558000: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [10772.832351] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10775.138739] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10782.157654] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10784.861582] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10788.633915] Lustre: Unmounted lustre-client [10820.771661] Key type lgssc unregistered [10821.266264] LNet: 206966:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10821.273483] LNetError: 206966:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10821.306381] LNet: Removed LNI 192.168.206.11@tcp [10822.310432] Key type .llcrypt unregistered [10822.316284] Key type ._llcrypt unregistered [10846.625790] Key type ._llcrypt registered [10846.636668] Key type .llcrypt registered [10848.574334] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 03:14:55 (1781594095) [10852.181835] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10852.244762] alg: No test for adler32 (adler32-zlib) [10853.519544] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [10853.986439] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [10855.888726] Key type lgssc registered [10857.615577] Lustre: Echo OBD driver; http://www.lustre.org/ [10923.785465] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10931.735128] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11009.693737] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11016.489942] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11059.572371] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11089.766569] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [11144.133326] Key type lgssc unregistered [11144.563911] LNet: 212222:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11144.570253] LNetError: 212222:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11144.583260] LNet: Removed LNI 192.168.206.11@tcp [11145.739676] Key type .llcrypt unregistered [11145.745764] Key type ._llcrypt unregistered [11199.419536] Key type ._llcrypt registered [11199.420794] Key type .llcrypt registered [11201.375493] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 03:20:48 (1781594448) [11252.183590] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11252.210188] alg: No test for adler32 (adler32-zlib) [11253.509899] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [11253.908569] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [11255.671738] Key type lgssc registered [11257.809401] Lustre: Echo OBD driver; http://www.lustre.org/ [11380.011333] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11389.359951] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11424.030549] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11485.670967] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 03:25:32 (1781594732) [11497.133392] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 03:25:44 (1781594744) [11546.251457] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11553.293615] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11581.509817] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11584.353070] Lustre: Mounted lustre-client [11590.978929] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [11593.669687] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [11595.802837] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [11598.011678] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [11605.010345] Lustre: lustre-MDT0000-mdc-ffff94c586c3b800: Connection to lustre-MDT0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [11621.343142] Lustre: 214123:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781594854/real 1781594854] req@00000000f3d3f120 x1868137237588224/t0(0) o400->MGC192.168.206.111@tcp@192.168.206.111@tcp:26/25 lens 224/224 e 0 to 1 dl 1781594870 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11621.376496] LustreError: MGC192.168.206.111@tcp: Connection to MGS (at 192.168.206.111@tcp) was lost; in progress operations using this service will fail [11631.606318] Lustre: Evicted from MGS (at 192.168.206.111@tcp) after server handle changed from 0x74b2fa105b48c031 to 0x74b2fa105b48c2a0 [11631.641562] Lustre: MGC192.168.206.111@tcp: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [11635.059048] Lustre: lustre-MDT0000-mdc-ffff94c586c3b800: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [11648.274946] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11650.431732] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11655.202991] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [11662.314901] Lustre: lustre-MDT0000-mdc-ffff94c586c3b800: Connection to lustre-MDT0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [11678.688059] Lustre: 214122:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781594911/real 1781594911] req@00000000de7cc46b x1868137237597696/t0(0) o400->MGC192.168.206.111@tcp@192.168.206.111@tcp:26/25 lens 224/224 e 0 to 1 dl 1781594927 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11678.724262] LustreError: MGC192.168.206.111@tcp: Connection to MGS (at 192.168.206.111@tcp) was lost; in progress operations using this service will fail [11678.748884] Lustre: Evicted from MGS (at 192.168.206.111@tcp) after server handle changed from 0x74b2fa105b48c2a0 to 0x74b2fa105b48c4b4 [11678.761381] Lustre: MGC192.168.206.111@tcp: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [11691.931940] Lustre: lustre-MDT0000-mdc-ffff94c586c3b800: Connection restored to 192.168.206.111@tcp (at 192.168.206.111@tcp) [11706.129452] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11708.976690] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11714.381064] Lustre: Unmounted lustre-client [11805.575896] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11812.917843] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11843.826879] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11844.945264] Lustre: Mounted lustre-client [11848.136280] Lustre: Unmounted lustre-client [11883.736929] Key type lgssc unregistered [11884.124058] LNet: 224662:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11884.144902] LNetError: 224662:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11884.173094] LNet: Removed LNI 192.168.206.11@tcp [11885.166210] Key type .llcrypt unregistered [11885.173246] Key type ._llcrypt unregistered [11908.351535] Key type ._llcrypt registered [11908.358745] Key type .llcrypt registered [11909.872219] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 03:32:37 (1781595157) [11916.921123] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11917.014792] alg: No test for adler32 (adler32-zlib) [11918.223595] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [11918.694550] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [11920.423482] Key type lgssc registered [11922.255317] Lustre: Echo OBD driver; http://www.lustre.org/ [11998.935220] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12007.850532] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12040.496391] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12042.519603] Lustre: Mounted lustre-client [12050.920358] Lustre: Unmounted lustre-client [12134.116310] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12142.183461] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12174.194837] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12175.181421] Lustre: Mounted lustre-client [12186.558165] Lustre: Unmounted lustre-client [12282.733562] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12291.572802] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12321.626687] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12322.471350] Lustre: Mounted lustre-client [12326.974727] Lustre: Unmounted lustre-client [12370.387421] Key type lgssc unregistered [12370.898338] LNet: 231658:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12370.908283] LNetError: 231658:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12370.924977] LNet: Removed LNI 192.168.206.11@tcp [12371.722525] Key type .llcrypt unregistered [12371.726586] Key type ._llcrypt unregistered [12397.779140] Key type ._llcrypt registered [12397.783387] Key type .llcrypt registered [12399.942777] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 03:40:46 (1781595646) [12404.943447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12404.978459] alg: No test for adler32 (adler32-zlib) [12406.230277] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [12406.583964] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [12408.311748] Key type lgssc registered [12410.170339] Lustre: Echo OBD driver; http://www.lustre.org/ [12464.972822] Key type lgssc unregistered [12465.356318] LNet: 233983:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12465.364367] LNetError: 233983:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12465.384432] LNet: Removed LNI 192.168.206.11@tcp [12466.227886] Key type .llcrypt unregistered [12466.232522] Key type ._llcrypt unregistered [12507.035608] Key type ._llcrypt registered [12507.044472] Key type .llcrypt registered [12507.951985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12508.020934] alg: No test for adler32 (adler32-zlib) [12509.507311] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [12509.930902] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [12511.681190] Key type lgssc registered [12513.947055] Lustre: Echo OBD driver; http://www.lustre.org/ [12620.993673] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12629.595507] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12662.951478] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12717.004775] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [12719.250602] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 03:46:06 (1781595966) [12757.107957] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12763.855891] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12790.272333] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12817.195946] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [12820.113506] Lustre: Mounted lustre-client [12825.081673] Lustre: lustre-OST0001-osc-ffff94c586c38000: Connection to lustre-OST0001 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [12833.580831] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12844.049606] Lustre: Unmounted lustre-client [12881.545957] Key type lgssc unregistered [12881.978343] LNet: 241048:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12881.983968] LNetError: 241048:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12882.014834] LNet: Removed LNI 192.168.206.11@tcp [12882.757604] Key type .llcrypt unregistered [12882.759494] Key type ._llcrypt unregistered [12921.068130] Key type ._llcrypt registered [12921.075700] Key type .llcrypt registered [12923.087334] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 03:49:30 (1781596170) [12942.216884] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12942.347259] alg: No test for adler32 (adler32-zlib) [12943.512495] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [12943.863968] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [12945.639261] Key type lgssc registered [12947.417728] Lustre: Echo OBD driver; http://www.lustre.org/ [13010.485915] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13016.940483] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13043.047985] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 03:51:30 (1781596290) [13084.028780] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13090.926599] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13116.552645] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13119.441779] Lustre: Mounted lustre-client [13132.306588] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [13140.086514] Lustre: Unmounted lustre-client [13252.319739] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13259.130496] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13317.213280] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13318.056953] Lustre: Mounted lustre-client [13321.902483] Lustre: Unmounted lustre-client [13358.699526] Key type lgssc unregistered [13358.984210] LNet: 248691:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13358.995850] LNetError: 248691:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13359.027995] LNet: Removed LNI 192.168.206.11@tcp [13359.691166] Key type .llcrypt unregistered [13359.693437] Key type ._llcrypt unregistered [13394.073843] Key type ._llcrypt registered [13394.076058] Key type .llcrypt registered [13394.333914] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13394.356175] alg: No test for adler32 (adler32-zlib) [13395.434460] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [13395.691106] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [13397.425450] Key type lgssc registered [13398.993472] Lustre: Echo OBD driver; http://www.lustre.org/ [13501.418681] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13507.890607] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13534.735615] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13579.539172] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [13581.721978] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 04:00:29 (1781596829) [13624.263080] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13630.631666] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13654.258259] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13663.278707] Lustre: Mounted lustre-client [13666.464063] Lustre: Unmounted lustre-client [13695.559830] Key type lgssc unregistered [13695.953938] LNet: 255291:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13695.964224] LNetError: 255291:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13695.984800] LNet: Removed LNI 192.168.206.11@tcp [13696.854862] Key type .llcrypt unregistered [13696.864061] Key type ._llcrypt unregistered [13716.983385] Key type ._llcrypt registered [13716.985018] Key type .llcrypt registered [13718.622261] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [13720.785948] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 04:02:48 (1781596968) [13731.573400] Key type .llcrypt unregistered [13731.575492] Key type ._llcrypt unregistered [13742.026408] Key type ._llcrypt registered [13742.064231] Key type .llcrypt registered [13742.431308] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13742.442409] alg: No test for adler32 (adler32-zlib) [13743.585445] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [13743.904852] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [13745.759489] Key type lgssc registered [13747.214233] Lustre: Echo OBD driver; http://www.lustre.org/ [13802.685242] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13820.357702] Lustre: Mounted lustre-client [13822.835651] Lustre: Unmounted lustre-client [13847.821536] Key type lgssc unregistered [13848.119320] LNet: 258760:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13848.131062] LNetError: 258760:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13848.155055] LNet: Removed LNI 192.168.206.11@tcp [13849.060930] Key type .llcrypt unregistered [13849.067330] Key type ._llcrypt unregistered [13867.155573] Key type ._llcrypt registered [13867.157658] Key type .llcrypt registered [13868.966431] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 04:05:16 (1781597116) [13871.915848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13871.987160] alg: No test for adler32 (adler32-zlib) [13873.156409] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [13873.513572] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [13875.288396] Key type lgssc registered [13876.980842] Lustre: Echo OBD driver; http://www.lustre.org/ [13970.637500] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14013.428916] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14020.846981] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14023.647494] Lustre: Mounted lustre-client [14027.142038] Lustre: Unmounted lustre-client [14053.711719] Key type lgssc unregistered [14054.061396] LNet: 262284:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14054.069442] LNetError: 262284:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14054.090706] LNet: Removed LNI 192.168.206.11@tcp [14054.782231] Key type .llcrypt unregistered [14054.787808] Key type ._llcrypt unregistered [14073.789588] Key type ._llcrypt registered [14073.793171] Key type .llcrypt registered [14075.357521] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 04:08:42 (1781597322) [14078.158876] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14078.182165] alg: No test for adler32 (adler32-zlib) [14079.414866] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [14079.851914] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [14081.623233] Key type lgssc registered [14083.331482] Lustre: Echo OBD driver; http://www.lustre.org/ [14145.187165] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14152.212481] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14175.760972] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14186.610238] Lustre: Mounted lustre-client [14191.590243] Lustre: lustre-MDT0000-mdc-ffff94c586ec5000: Connection to lustre-MDT0000 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [14197.049900] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [14197.075131] LustreError: 265420:0:(file.c:6088:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [14197.512816] LustreError: MGC192.168.206.111@tcp: Connection to MGS (at 192.168.206.111@tcp) was lost; in progress operations using this service will fail [14197.550522] Lustre: Unmounted lustre-client [14218.922352] Key type lgssc unregistered [14219.266735] LNet: 265833:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14219.274243] LNetError: 265833:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14219.296184] LNet: Removed LNI 192.168.206.11@tcp [14220.057579] Key type .llcrypt unregistered [14220.059539] Key type ._llcrypt unregistered [14237.577228] Key type ._llcrypt registered [14237.580851] Key type .llcrypt registered [14239.063240] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 04:11:26 (1781597486) [14241.661212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14241.713414] alg: No test for adler32 (adler32-zlib) [14242.840688] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [14243.107930] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [14244.872939] Key type lgssc registered [14246.662410] Lustre: Echo OBD driver; http://www.lustre.org/ [14305.960344] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14314.138458] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14334.489885] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14350.413399] Lustre: Mounted lustre-client [14355.454438] Lustre: lustre-MDT0001-mdc-ffff94c5872d7000: Connection to lustre-MDT0001 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [14369.929623] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [14372.457804] Lustre: Unmounted lustre-client [14392.846160] Key type lgssc unregistered [14393.222790] LNet: 269394:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14393.229810] LNetError: 269394:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14393.263931] LNet: Removed LNI 192.168.206.11@tcp [14394.217474] Key type .llcrypt unregistered [14394.221822] Key type ._llcrypt unregistered [14414.639310] Key type ._llcrypt registered [14414.641594] Key type .llcrypt registered [14416.529758] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 04:14:23 (1781597663) [14439.091062] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14439.151577] alg: No test for adler32 (adler32-zlib) [14440.344726] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [14440.695786] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [14442.399933] Key type lgssc registered [14444.123880] Lustre: Echo OBD driver; http://www.lustre.org/ [14574.725532] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14577.575469] Lustre: Mounted lustre-client [14623.743528] Lustre: lustre-MDT0001-mdc-ffff94c5883cf800: Connection to lustre-MDT0001 (at 192.168.206.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [14637.040712] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [14647.444783] Lustre: Unmounted lustre-client [14681.437657] Key type lgssc unregistered [14681.840865] LNet: 275179:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14681.864983] LNetError: 275179:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14681.920658] LNet: Removed LNI 192.168.206.11@tcp [14683.014413] Key type .llcrypt unregistered [14683.023609] Key type ._llcrypt unregistered [14702.625896] Key type ._llcrypt registered [14702.629185] Key type .llcrypt registered [14704.400432] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 04:19:11 (1781597951) [14706.635979] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [14708.505900] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 04:19:16 (1781597956) [14710.703149] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [14712.733327] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 04:19:20 (1781597960) [14714.702969] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [14716.985857] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 04:19:24 (1781597964) [14718.881902] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [14720.998226] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 04:19:28 (1781597968) [14723.281668] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [14725.780861] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 04:19:33 (1781597973) [14735.899500] Key type .llcrypt unregistered [14735.901776] Key type ._llcrypt unregistered [14746.019189] Key type ._llcrypt registered [14746.031518] Key type .llcrypt registered [14746.712290] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14746.746909] alg: No test for adler32 (adler32-zlib) [14748.064281] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [14748.462530] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [14750.296228] Key type lgssc registered [14752.397784] Lustre: Echo OBD driver; http://www.lustre.org/ [14836.279719] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14842.648523] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14867.060488] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14868.677337] Lustre: Mounted lustre-client [14871.817410] Lustre: Unmounted lustre-client [14904.276477] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 04:22:31 (1781598151) [14944.823626] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14951.696743] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14991.180668] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14991.932838] Lustre: Mounted lustre-client [14994.694234] Lustre: Unmounted lustre-client [15048.127660] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 04:24:55 (1781598295) [15049.567326] LNet: 1 local NIs in recovery (showing 1): 192.168.206.11@tcp [15719.490117] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [15725.832780] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [15766.569491] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [15915.400317] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 04:39:22 (1781599162) [15931.423219] Key type lgssc unregistered [15931.797687] LNet: 288649:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15931.807762] LNetError: 288649:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [15931.838152] LNet: Removed LNI 192.168.206.11@tcp [15932.732642] Key type .llcrypt unregistered [15932.735392] Key type ._llcrypt unregistered [16015.717219] Key type ._llcrypt registered [16015.724188] Key type .llcrypt registered [16016.654630] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16016.681328] alg: No test for adler32 (adler32-zlib) [16018.068497] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [16018.338296] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [16020.031219] Key type lgssc registered [16022.041760] Lustre: Echo OBD driver; http://www.lustre.org/ [16134.632415] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [16141.137453] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [16167.399402] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [16212.872568] Key type lgssc unregistered [16213.262614] LNet: 292325:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16213.300860] LNetError: 292325:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [16213.342971] LNet: Removed LNI 192.168.206.11@tcp [16214.356593] Key type .llcrypt unregistered [16214.358461] Key type ._llcrypt unregistered [16253.741664] Key type ._llcrypt registered [16253.747217] Key type .llcrypt registered [16255.479505] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 04:45:02 (1781599502) [16286.978967] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16287.108145] alg: No test for adler32 (adler32-zlib) [16288.417296] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [16288.695143] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [16290.424825] Key type lgssc registered [16292.783221] Lustre: Echo OBD driver; http://www.lustre.org/ [16365.234804] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 04:46:51 (1781599611) [16382.709517] Key type lgssc unregistered [16383.150227] LNet: 295889:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16383.159179] LNetError: 295889:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [16383.189276] LNet: Removed LNI 192.168.206.11@tcp [16384.275602] Key type .llcrypt unregistered [16384.277455] Key type ._llcrypt unregistered [16401.431370] Key type ._llcrypt registered [16401.434659] Key type .llcrypt registered [16401.907592] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16401.922616] alg: No test for adler32 (adler32-zlib) [16403.259549] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [16403.667454] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [16405.479212] Key type lgssc registered [16407.390300] Lustre: Echo OBD driver; http://www.lustre.org/ [16484.253783] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [16491.752800] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [16522.633445] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [16913.574770] LNet: Added LNI 192.168.206.11@tcp50 [8/256/0/180] [16916.652529] LNet: Removed LNI 192.168.206.11@tcp [16919.257160] Lustre: 300290:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff94c590ab9000: config has no valid NIDs, force dynamic NIDs [16920.518898] Lustre: Mounted lustre-client [16920.789128] Lustre: Unmounted lustre-client [16924.467335] Key type lgssc unregistered [16924.956981] LNet: 300549:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16924.969076] LNetError: 300549:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [16925.003887] LNet: Removed LNI 192.168.206.11@tcp50 [16925.726779] Key type .llcrypt unregistered [16925.737104] Key type ._llcrypt unregistered [16946.457814] Key type ._llcrypt registered [16946.466396] Key type .llcrypt registered [16947.319710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16947.357694] alg: No test for adler32 (adler32-zlib) [16948.816199] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [16949.265844] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [16951.031866] Key type lgssc registered [16952.801407] Lustre: Echo OBD driver; http://www.lustre.org/ [17023.212898] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [17033.786421] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [17044.121945] LNet: Added LNI 192.168.206.11@tcp50 [8/256/0/180] [17045.273526] LNet: Removed LNI 192.168.206.11@tcp [17046.526497] Lustre: 303230:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff94c587bac800: config has no valid NIDs, force dynamic NIDs [17047.760550] Lustre: Mounted lustre-client [17048.413128] Lustre: Unmounted lustre-client [17086.834158] Key type lgssc unregistered [17087.248623] LNet: 304100:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17087.260458] LNetError: 304100:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [17087.283385] LNet: Removed LNI 192.168.206.11@tcp50 [17087.914451] Key type .llcrypt unregistered [17087.919448] Key type ._llcrypt unregistered [17103.364577] Key type ._llcrypt registered [17103.367190] Key type .llcrypt registered [17105.128774] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 04:59:12 (1781600352) [17134.782211] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [17134.808149] alg: No test for adler32 (adler32-zlib) [17136.212415] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [17136.678730] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [17138.655596] Key type lgssc registered [17140.528471] Lustre: Echo OBD driver; http://www.lustre.org/ [17252.714485] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [17260.422889] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [17296.222412] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [17345.165467] Key type lgssc unregistered [17345.570864] LNet: 308846:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17345.600264] LNetError: 308846:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [17345.638899] LNet: Removed LNI 192.168.206.11@tcp [17346.481848] Key type .llcrypt unregistered [17346.486896] Key type ._llcrypt unregistered [17361.254560] Key type ._llcrypt registered [17361.256380] Key type .llcrypt registered [17363.411364] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 05:03:30 (1781600610) [17366.855402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [17366.988783] alg: No test for adler32 (adler32-zlib) [17368.415631] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [17368.714983] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [17370.455616] Key type lgssc registered [17372.395488] Lustre: Echo OBD driver; http://www.lustre.org/ [17441.596365] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [17449.378924] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [17482.027233] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [17484.153130] Lustre: Mounted lustre-client [17559.031098] Lustre: Mounted fs15246-client [17577.279073] Lustre: Unmounted fs15246-client [17607.919970] Lustre: Unmounted lustre-client [17646.178882] Key type lgssc unregistered [17646.581055] LNet: 313695:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17646.604241] LNetError: 313695:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [17646.629246] LNet: Removed LNI 192.168.206.11@tcp [17647.510360] Key type .llcrypt unregistered [17647.516944] Key type ._llcrypt unregistered [17661.850583] Key type ._llcrypt registered [17661.856865] Key type .llcrypt registered [17663.653749] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 05:08:30 (1781600910) [17665.632494] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [17665.661422] alg: No test for adler32 (adler32-zlib) [17666.923890] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [17667.247726] LNet: Added LNI 192.168.206.11@tcp [8/256/0/180] [17668.943987] Key type lgssc registered [17670.397872] Lustre: Echo OBD driver; http://www.lustre.org/ [17826.575814] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [17833.983638] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [17865.368407] Lustre: DEBUG MARKER: oleg611-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [17999.160776] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 17592 sec ========= 05:14:06 (1781601246) [18001.055975] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 05:14:08 (1781601248) === [18004.706430] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 05:14:12 (1781601252) === [18041.004149] Key type lgssc unregistered [18041.371445] LNet: 322946:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [18041.380994] LNetError: 322946:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [18041.405188] LNet: Removed LNI 192.168.206.11@tcp [18042.215796] Key type .llcrypt unregistered [18042.218856] Key type ._llcrypt unregistered