[ 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-8.fc42 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 536515783 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.996 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 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 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.001014] APIC: Switch to symmetric I/O mode setup [ 0.002564] x2apic enabled [ 0.003010] Switched APIC routing to physical x2apic. [ 0.004014] kvm-guest: setup PV IPIs [ 0.007888] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229833f6470, max_idle_ns: 440795327230 ns [ 0.008029] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399996) [ 0.009018] pid_max: default: 32768 minimum: 301 [ 0.011151] LSM: Security Framework initializing [ 0.012073] Yama: becoming mindful. [ 0.013059] SELinux: Initializing. [ 0.014091] *** VALIDATE selinux *** [ 0.024690] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.029878] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030166] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031131] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033136] *** VALIDATE tmpfs *** [ 0.034529] *** VALIDATE proc *** [ 0.035289] *** VALIDATE cgroup *** [ 0.036013] *** VALIDATE cgroup2 *** [ 0.037310] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038177] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040036] Spectre V2 : User space: Vulnerable [ 0.041015] Speculative Store Bypass: Vulnerable [ 0.044466] debug: unmapping init [mem 0xffffffffbac59000-0xffffffffbac60fff] [ 0.047217] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049000] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.050037] ... version: 2 [ 0.051017] ... bit width: 48 [ 0.052018] ... generic registers: 4 [ 0.053017] ... value mask: 0000ffffffffffff [ 0.054023] ... max period: 00007fffffffffff [ 0.055021] ... fixed-purpose events: 3 [ 0.056016] ... event mask: 000000070000000f [ 0.057387] rcu: Hierarchical SRCU implementation. [ 0.059704] smp: Bringing up secondary CPUs ... [ 0.060697] x86: Booting SMP configuration: [ 0.061038] .... node #0, CPUs: #1 #2 #3 [ 0.065264] smp: Brought up 1 node, 4 CPUs [ 0.067022] smpboot: Max logical packages: 1 [ 0.068025] smpboot: Total of 4 processors activated (19199.96 BogoMIPS) [ 0.271628] node 0 deferred pages initialised in 201ms [ 0.276454] devtmpfs: initialized [ 0.277305] x86/mm: Memory block size: 128MB [ 0.281049] gcov: version magic: 0x41383552 [ 0.283392] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.284110] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.285318] pinctrl core: initialized pinctrl subsystem [ 0.286296] [ 0.286905] ************************************************************* [ 0.287029] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.288022] ** ** [ 0.289018] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.290019] ** ** [ 0.291021] ** This means that this kernel is built to expose internal ** [ 0.292024] ** IOMMU data structures, which may compromise security on ** [ 0.293020] ** your system. ** [ 0.294021] ** ** [ 0.295233] ** If you see this message and you are not debugging the ** [ 0.296024] ** kernel, report this immediately to your vendor! ** [ 0.297024] ** ** [ 0.298024] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.299018] ************************************************************* [ 0.300758] NET: Registered protocol family 16 [ 0.301661] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.302083] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.303077] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.305048] cpuidle: using governor menu [ 0.307458] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.309541] PCI: Using configuration type 1 for base access [ 0.311159] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.322103] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.324052] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.329054] cryptd: max_cpu_qlen set to 1000 [ 0.333290] ACPI: Added _OSI(Module Device) [ 0.334023] ACPI: Added _OSI(Processor Device) [ 0.336016] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.338020] ACPI: Added _OSI(Processor Aggregator Device) [ 0.343762] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.350407] ACPI: Interpreter enabled [ 0.351083] ACPI: PM: (supports S0 S3 S4 S5) [ 0.352017] ACPI: Using IOAPIC for interrupt routing [ 0.353342] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.354469] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.366067] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.370064] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.375033] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.379108] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.386248] acpiphp: Slot [2] registered [ 0.389131] acpiphp: Slot [5] registered [ 0.391184] acpiphp: Slot [6] registered [ 0.392159] acpiphp: Slot [3] registered [ 0.394140] acpiphp: Slot [4] registered [ 0.396167] acpiphp: Slot [7] registered [ 0.398140] acpiphp: Slot [8] registered [ 0.399153] acpiphp: Slot [9] registered [ 0.401135] acpiphp: Slot [10] registered [ 0.403142] acpiphp: Slot [11] registered [ 0.405160] acpiphp: Slot [12] registered [ 0.407157] acpiphp: Slot [13] registered [ 0.409204] acpiphp: Slot [14] registered [ 0.411174] acpiphp: Slot [15] registered [ 0.412108] acpiphp: Slot [16] registered [ 0.414130] acpiphp: Slot [17] registered [ 0.416138] acpiphp: Slot [18] registered [ 0.418204] acpiphp: Slot [19] registered [ 0.420381] acpiphp: Slot [20] registered [ 0.422125] acpiphp: Slot [21] registered [ 0.425186] acpiphp: Slot [22] registered [ 0.427166] acpiphp: Slot [23] registered [ 0.430397] acpiphp: Slot [24] registered [ 0.432150] acpiphp: Slot [25] registered [ 0.434168] acpiphp: Slot [26] registered [ 0.438164] acpiphp: Slot [27] registered [ 0.441190] acpiphp: Slot [28] registered [ 0.443399] acpiphp: Slot [29] registered [ 0.446247] acpiphp: Slot [30] registered [ 0.449072] acpiphp: Slot [31] registered [ 0.451121] PCI host bridge to bus 0000:00 [ 0.452025] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.455030] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.457026] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.460035] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.464035] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.467031] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.470240] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.473376] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.477631] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.486000] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.491382] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.494026] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.496245] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.500023] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.504136] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.507834] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.512057] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.517633] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.524018] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.537020] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.543019] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.551124] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.561025] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.570022] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.595022] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.608869] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.616022] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.629024] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.655027] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.668996] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.672504] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.676460] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.680932] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.685313] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.693084] iommu: Default domain type: Passthrough [ 0.695681] SCSI subsystem initialized [ 0.697159] ACPI: bus type USB registered [ 0.699178] usbcore: registered new interface driver usbfs [ 0.703179] usbcore: registered new interface driver hub [ 0.706113] usbcore: registered new device driver usb [ 0.709409] pps_core: LinuxPPS API ver. 1 registered [ 0.712017] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.722256] PTP clock support registered [ 0.726276] EDAC MC: Ver: 3.0.0 [ 0.729384] PCI: Using ACPI for IRQ routing [ 0.731713] NetLabel: Initializing [ 0.732000] NetLabel: domain hash size = 128 [ 0.736019] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.738097] NetLabel: unlabeled traffic allowed by default [ 0.741179] vgaarb: loaded [ 0.743375] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.745022] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.755005] clocksource: Switched to clocksource kvm-clock [ 0.884156] VFS: Disk quotas dquot_6.6.0 [ 0.887224] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.891599] *** VALIDATE ramfs *** [ 0.893761] *** VALIDATE hugetlbfs *** [ 0.896844] pnp: PnP ACPI init [ 0.901220] pnp: PnP ACPI: found 6 devices [ 0.934141] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.938882] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.942314] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.945484] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.949202] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.952618] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.956877] NET: Registered protocol family 2 [ 0.960805] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.968178] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.973654] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.980988] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.985937] TCP: Hash tables configured (established 65536 bind 65536) [ 0.990229] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.994662] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.997717] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.000797] NET: Registered protocol family 1 [ 1.004551] RPC: Registered named UNIX socket transport module. [ 1.007775] RPC: Registered udp transport module. [ 1.010208] RPC: Registered tcp transport module. [ 1.012091] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.015622] NET: Registered protocol family 44 [ 1.017759] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.020166] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.022770] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.025757] PCI: CLS 0 bytes, default 64 [ 1.027930] Unpacking initramfs... [ 2.696701] debug: unmapping init [mem 0xffff8b95fcc64000-0xffff8b95fffcffff] [ 2.703438] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.704996] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.707019] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229833f6470, max_idle_ns: 440795327230 ns [ 3.338632] Initialise system trusted keyrings [ 3.340883] Key type blacklist registered [ 3.343838] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.355786] zbud: loaded [ 3.360482] *** VALIDATE nfs *** [ 3.361866] *** VALIDATE nfs4 *** [ 3.364320] pstore: using deflate compression [ 3.369607] Platform Keyring initialized [ 3.500949] NET: Registered protocol family 38 [ 3.502557] Key type asymmetric registered [ 3.503594] Asymmetric key parser 'x509' registered [ 3.505376] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.509784] io scheduler mq-deadline registered [ 3.511740] io scheduler kyber registered [ 3.515359] io scheduler bfq registered [ 3.518092] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.521758] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.525503] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.528052] ACPI: Power Button [PWRF] [ 3.534243] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.541827] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.550186] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.579977] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.608701] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.614578] Non-volatile memory driver v1.3 [ 3.615889] Linux agpgart interface v0.103 [ 3.645672] virtio_blk virtio1: [vda] 134584 512-byte logical blocks (68.9 MB/65.7 MiB) [ 3.648322] vda: detected capacity change from 0 to 68907008 [ 3.668226] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.671274] vdb: detected capacity change from 0 to 1073741824 [ 3.679584] libphy: Fixed MDIO Bus: probed [ 3.683905] usbcore: registered new interface driver usbserial_generic [ 3.685815] usbserial: USB Serial support registered for generic [ 3.687424] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.690474] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.691640] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.693255] mousedev: PS/2 mouse device common for all mice [ 3.695743] rtc_cmos 00:05: RTC can wake from S4 [ 3.697758] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.700612] rtc_cmos 00:05: registered as rtc0 [ 3.701792] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.703222] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.707408] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.708784] intel_pstate: CPU model not supported [ 3.714278] hid: raw HID events driver (C) Jiri Kosina [ 3.716261] usbcore: registered new interface driver usbhid [ 3.718629] usbhid: USB HID core driver [ 3.720129] drop_monitor: Initializing network drop monitor service [ 3.722685] Initializing XFRM netlink socket [ 3.724826] NET: Registered protocol family 10 [ 3.727579] Segment Routing with IPv6 [ 3.729508] NET: Registered protocol family 17 [ 3.731656] mpls_gso: MPLS GSO support [ 3.738812] RAS: Correctable Errors collector initialized. [ 3.740982] AVX version of gcm_enc/dec engaged. [ 3.742128] AES CTR mode by8 optimization enabled [ 3.814382] sched_clock: Marking stable (3814310549, 0)->(5033024485, -1218713936) [ 3.817432] registered taskstats version 1 [ 3.818747] Loading compiled-in X.509 certificates [ 3.820372] zswap: loaded using pool lzo/zbud [ 3.842753] Key type big_key registered [ 3.855820] Key type encrypted registered [ 3.857283] ima: No TPM chip found, activating TPM-bypass! [ 3.858828] ima: Allocated hash algorithm: sha1 [ 3.859843] ima: No architecture policies found [ 3.861749] evm: Initialising EVM extended attributes: [ 3.864469] evm: security.selinux [ 3.865737] evm: security.ima [ 3.866884] evm: security.capability [ 3.868517] evm: HMAC attrs: 0x1 [ 3.871648] rtc_cmos 00:05: setting system clock to 2026-03-15 20:39:02 UTC (1773607142) [ 3.880648] debug: unmapping init [mem 0xffffffffbbc03000-0xffffffffbbdfffff] [ 3.883108] debug: unmapping init [mem 0xffffffffba982000-0xffffffffbac58fff] [ 3.893468] Write protecting the kernel read-only data: 28672k [ 3.897715] debug: unmapping init [mem 0xffffffffb9003000-0xffffffffb91fffff] [ 3.899623] debug: unmapping init [mem 0xffffffffb9914000-0xffffffffb99fffff] [ 3.942558] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.954862] systemd[1]: Detected virtualization kvm. [ 3.957848] systemd[1]: Detected architecture x86-64. [ 3.960827] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.985646] systemd[1]: No hostname configured. [ 3.987427] systemd[1]: Set hostname to . [ 3.989937] random: systemd: uninitialized urandom read (16 bytes read) [ 3.992810] systemd[1]: Initializing machine ID from random generator. [ 4.127756] random: systemd: uninitialized urandom read (16 bytes read) [ 4.130775] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 4.134871] random: systemd: uninitialized urandom read (16 bytes read) [ 4.136869] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 4.141821] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Swap. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ 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... [ 4.861589] device-mapper: uevent: version 1.0.3 [ 4.864437] 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. [ 5.631251] random: fast init done [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.708243] virtio_net virtio0 ens2: renamed from eth0 [ 5.774805] scsi host0: ata_piix [ 5.801731] scsi host1: ata_piix [ 5.803366] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.805229] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.702697] dracut-initqueue[576]: RTNETLINK answers: File exists [ 10.376460] random: crng init done [ 10.378282] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.993826] 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 dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Stopped target Slices. [ 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... [ 12.305667] printk: systemd: 26 output lines suppressed due to ratelimiting [ 12.636680] SELinux: Disabled at runtime. [ 12.694164] 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) [ 12.701594] systemd[1]: Detected virtualization kvm. [ 12.703054] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 13.249560] systemd[1]: initrd-switch-root.service: Succeeded. [ 13.253725] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 13.264560] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 13.270518] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 13.275307] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 13.282832] systemd[1]: Starting Journal Service... Starting Journal Service... [ 13.305443] systemd[1]: Listening on Process Core Dump Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Switch Root. [ OK ] Started Forward Password Requests to Wall Directory Watch. Starting Create list of required st…ce nodes for the current kernel... Activating swap /dev/disk/by-label/SWAP... Mounting Huge Pages File System... Starting Apply Kernel Variables... Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target rpc_pipefs.target. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 13.392930] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Mounting Kernel Debug File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-getty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Initrd Root File System. Mounting POSIX Message Queue File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ 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 ] Mounted POSIX Message Queue File System. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 13.777096] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 14.036488] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 14.083540] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 14.226290] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 14.249770] EDAC sbridge: Ver: 1.1.2 [ 15.439459] Key type dns_resolver registered [ 15.809596] NFS: Registering the id_resolver key type [ 15.812479] Key type id_resolver registered [ 15.814731] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started Login Service. Starting Hostname Service... [ 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... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg105-client login: [ 29.015544] hrtimer: interrupt took 6534168 ns [ 105.327089] libcfs: loading out-of-tree module taints kernel. [ 105.496960] Key type ._llcrypt registered [ 105.502444] Key type .llcrypt registered [ 106.300898] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 106.322183] alg: No test for adler32 (adler32-zlib) [ 108.288238] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 110.416227] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 112.527183] Key type lgssc registered [ 116.293543] Lustre: Echo OBD driver; http://www.lustre.org/ [ 377.588954] Lustre: Mounted lustre-client [ 385.174344] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 403.423959] Lustre: lustre-OST0000-osc-ffff8b96481f0800: disconnect after 23s idle [ 408.248155] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing check_logdir /tmp/testlogs/ [ 416.679514] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing yml_node [ 423.889471] Lustre: DEBUG MARKER: Client: 2.17.51.1 [ 428.096835] Lustre: DEBUG MARKER: MDS: 2.17.51.1 [ 432.213698] Lustre: DEBUG MARKER: OSS: 2.17.51.1 [ 434.649658] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sun Mar 15 16:46:11 EDT 2026 [ 469.064351] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 472.858165] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 478.868711] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 489.198215] Lustre: Unmounted lustre-client [ 660.462892] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 669.843972] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 708.546794] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 762.518906] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 16:51:38 (1773607898) [ 766.393200] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 768.850428] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 16:51:45 (1773607905) [ 814.155126] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 820.184807] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 852.236896] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 853.260394] Lustre: Mounted lustre-client [ 861.420952] LustreError: 14491:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9645409800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 861.535465] Lustre: Unmounted lustre-client [ 899.329033] Key type lgssc unregistered [ 899.906496] LNet: 14900:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 899.922620] LNetError: 14900:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 899.986993] LNet: Removed LNI 192.168.201.5@tcp [ 901.726780] Key type .llcrypt unregistered [ 901.736313] Key type ._llcrypt unregistered [ 930.360371] Key type ._llcrypt registered [ 930.366620] Key type .llcrypt registered [ 933.041868] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 16:54:28 (1773608068) [ 936.467343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 936.560738] alg: No test for adler32 (adler32-zlib) [ 938.247497] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 938.694932] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 940.520980] Key type lgssc registered [ 942.951622] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1058.057771] Lustre: Mounted lustre-client [ 1163.898170] LustreError: 18301:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9643eeb000: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 1163.960617] Lustre: Unmounted lustre-client [ 1204.357329] Key type lgssc unregistered [ 1204.748645] LNet: 18768:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1204.773937] LNetError: 18768:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1204.797225] LNet: Removed LNI 192.168.201.5@tcp [ 1205.899027] Key type .llcrypt unregistered [ 1205.907847] Key type ._llcrypt unregistered [ 1222.882340] Key type ._llcrypt registered [ 1222.884683] Key type .llcrypt registered [ 1224.952869] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 16:59:21 (1773608361) [ 1228.639337] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1228.681024] alg: No test for adler32 (adler32-zlib) [ 1230.057285] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 1230.418473] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 1232.209239] Key type lgssc registered [ 1234.529714] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1316.035952] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1322.916799] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1353.368720] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1356.672996] Lustre: Mounted lustre-client [ 1374.214055] LustreError: 21906:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9645452000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1374.447352] Lustre: Unmounted lustre-client [ 1409.329526] Key type lgssc unregistered [ 1409.645210] LNet: 22311:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1409.661638] LNetError: 22311:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1409.690035] LNet: Removed LNI 192.168.201.5@tcp [ 1410.607705] Key type .llcrypt unregistered [ 1410.611298] Key type ._llcrypt unregistered [ 1426.604987] Key type ._llcrypt registered [ 1426.607316] Key type .llcrypt registered [ 1428.747409] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 17:02:44 (1773608564) [ 1433.033273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1433.067361] alg: No test for adler32 (adler32-zlib) [ 1434.352407] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 1434.770684] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 1436.655232] Key type lgssc registered [ 1438.347190] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1511.599415] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1518.322714] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1550.008165] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1553.183563] Lustre: Mounted lustre-client [ 1560.610299] LustreError: 25028:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b965240b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1560.722261] Lustre: Unmounted lustre-client [ 1600.937142] Key type lgssc unregistered [ 1601.502146] LNet: 25433:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1601.522975] LNetError: 25433:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1601.550218] LNet: Removed LNI 192.168.201.5@tcp [ 1603.215322] Key type .llcrypt unregistered [ 1603.224526] Key type ._llcrypt unregistered [ 1629.143807] Key type ._llcrypt registered [ 1629.148543] Key type .llcrypt registered [ 1631.531711] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 17:06:07 (1773608767) [ 1635.960534] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1635.977563] alg: No test for adler32 (adler32-zlib) [ 1637.339474] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 1637.687250] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 1639.423295] Key type lgssc registered [ 1642.206210] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1726.873849] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1733.737809] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1770.124558] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1773.490761] Lustre: Mounted lustre-client [ 1783.797160] Lustre: lustre-MDT0000-mdc-ffff8b9648195800: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1794.037074] Lustre: lustre-MDT0001-mdc-ffff8b9648195800: Connection to lustre-MDT0001 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1801.192827] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1805.279154] Lustre: 26395:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773608927/real 1773608927] req@00000000cf86b6d0 x1859763595909120/t0(0) o400->MGC192.168.201.105@tcp@192.168.201.105@tcp:26/25 lens 224/224 e 0 to 1 dl 1773608943 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1805.326689] LustreError: MGC192.168.201.105@tcp: Connection to MGS (at 192.168.201.105@tcp) was lost; in progress operations using this service will fail [ 1811.698644] Lustre: Unmounted lustre-client [ 1837.841455] Key type lgssc unregistered [ 1838.314524] LNet: 29019:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1838.331562] LNetError: 29019:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1838.376378] LNet: Removed LNI 192.168.201.5@tcp [ 1839.262193] Key type .llcrypt unregistered [ 1839.272687] Key type ._llcrypt unregistered [ 1865.116579] Key type ._llcrypt registered [ 1865.119639] Key type .llcrypt registered [ 1867.402502] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 17:10:03 (1773609003) [ 1871.040957] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1871.198319] alg: No test for adler32 (adler32-zlib) [ 1872.686736] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 1873.221571] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 1875.064523] Key type lgssc registered [ 1877.655058] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1952.813025] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1958.967107] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1989.685191] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1992.859818] Lustre: Mounted lustre-client [ 2002.949283] Lustre: lustre-OST0000-osc-ffff8b9648197000: Connection to lustre-OST0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2008.154375] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2018.587906] Lustre: Unmounted lustre-client [ 2055.181247] Key type lgssc unregistered [ 2055.761949] LNet: 32558:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2055.774409] LNetError: 32558:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2055.817145] LNet: Removed LNI 192.168.201.5@tcp [ 2056.830329] Key type .llcrypt unregistered [ 2056.833812] Key type ._llcrypt unregistered [ 2083.370834] Key type ._llcrypt registered [ 2083.374546] Key type .llcrypt registered [ 2085.765901] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 17:13:41 (1773609221) [ 2090.580745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2090.667787] alg: No test for adler32 (adler32-zlib) [ 2092.181676] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 2092.850379] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 2094.703993] Key type lgssc registered [ 2096.644817] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2174.695435] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2181.067572] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2211.379153] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2214.820130] Lustre: Mounted lustre-client [ 2221.291343] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 2226.310585] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 2238.902392] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-03-1517h16m15s [ 2242.105475] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 2245.609984] Lustre: lustre-MDT0000-mdc-ffff8b9660688000: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2255.941632] LustreError: 36273:0:(lmv_obd.c:1434:lmv_statfs()) lustre-MDT0000-mdc-ffff8b9660688000: can't stat MDS #0: rc = -19 [ 2258.292705] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 2269.154252] Lustre: 33522:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773609391/real 1773609391] req@00000000350e9d9d x1859764073013888/t0(0) o400->MGC192.168.201.105@tcp@192.168.201.105@tcp:26/25 lens 224/224 e 0 to 1 dl 1773609407 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2269.226672] LustreError: MGC192.168.201.105@tcp: Connection to MGS (at 192.168.201.105@tcp) was lost; in progress operations using this service will fail [ 2278.439102] Lustre: Evicted from MGS (at 192.168.201.105@tcp) after server handle changed from 0x6e38c8661480af80 to 0x6e38c8661480b059 [ 2278.476894] Lustre: MGC192.168.201.105@tcp: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [ 2286.073451] LustreError: lustre-MDT0000-mdc-ffff8b9660688000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 2286.131087] Lustre: lustre-MDT0000-mdc-ffff8b9660688000: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [ 2296.830122] Lustre: DEBUG MARKER: Wait for df (36273) ... [ 2298.952609] Lustre: DEBUG MARKER: done [ 2302.538053] LustreError: 36895:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9660688000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2302.742254] Lustre: Unmounted lustre-client [ 2343.795594] Key type lgssc unregistered [ 2344.461900] LNet: 37300:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2344.480205] LNetError: 37300:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2344.544894] LNet: Removed LNI 192.168.201.5@tcp [ 2345.647407] Key type .llcrypt unregistered [ 2345.650524] Key type ._llcrypt unregistered [ 2400.134333] Key type ._llcrypt registered [ 2400.142345] Key type .llcrypt registered [ 2403.314771] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 17:18:58 (1773609538) [ 2408.516220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2408.718979] alg: No test for adler32 (adler32-zlib) [ 2410.198995] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 2410.699634] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 2412.639965] Key type lgssc registered [ 2415.255215] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2500.033978] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2506.437986] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2538.560241] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2541.631301] Lustre: Mounted lustre-client [ 2548.592935] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-03-1517h21m24s [ 2551.604375] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 2562.121431] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 2565.941388] Lustre: DEBUG MARKER: Creating a test file and stat it [ 2586.083724] Lustre: 41378:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773609704/real 1773609704] req@00000000b67f4d56 x1859764406459648/t0(0) o101->lustre-MDT0000-mdc-ffff8b9645103000@192.168.201.105@tcp:12/10 lens 576/1152 e 0 to 1 dl 1773609724 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 2586.113486] Lustre: lustre-MDT0000-mdc-ffff8b9645103000: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2586.189279] Lustre: lustre-MDT0000-mdc-ffff8b9645103000: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [ 2608.842264] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 2614.081987] Lustre: DEBUG MARKER: done [ 2620.935189] LustreError: 41863:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9645103000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2621.107844] Lustre: Unmounted lustre-client [ 2656.886298] Key type lgssc unregistered [ 2657.207106] LNet: 42267:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2657.215950] LNetError: 42267:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2657.248517] LNet: Removed LNI 192.168.201.5@tcp [ 2658.559874] Key type .llcrypt unregistered [ 2658.569757] Key type ._llcrypt unregistered [ 2704.951668] Key type ._llcrypt registered [ 2704.954335] Key type .llcrypt registered [ 2706.543410] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 17:24:03 (1773609843) [ 2708.883478] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2711.841535] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 17:24:08 (1773609848) [ 2717.977932] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2718.085454] alg: No test for adler32 (adler32-zlib) [ 2719.472201] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 2720.110547] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 2722.007491] Key type lgssc registered [ 2724.375422] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2771.183611] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 17:25:05 (1773609905) [ 2822.238793] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2828.786959] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2857.001049] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2859.235735] Lustre: Mounted lustre-client [ 2864.202648] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2870.448142] LustreError: 46627:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9645451000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2870.620384] Lustre: Unmounted lustre-client [ 2891.676811] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2945.338482] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2950.953334] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2951.943819] Lustre: Mounted lustre-client [ 2958.240555] LustreError: 47888:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9648193800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2958.389438] Lustre: Unmounted lustre-client [ 3055.706208] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3062.790586] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3063.720633] Lustre: Mounted lustre-client [ 3069.635181] LustreError: 49019:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9660688000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3069.858257] Lustre: Unmounted lustre-client [ 3100.581863] Lustre: DEBUG MARKER: files compared the same [ 3126.918685] Key type lgssc unregistered [ 3127.360662] LNet: 49729:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3127.368672] LNetError: 49729:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3127.411695] LNet: Removed LNI 192.168.201.5@tcp [ 3128.566906] Key type .llcrypt unregistered [ 3128.573534] Key type ._llcrypt unregistered [ 3151.642665] Key type ._llcrypt registered [ 3151.646574] Key type .llcrypt registered [ 3154.144956] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 17:31:29 (1773610289) [ 3157.348463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3157.450722] alg: No test for adler32 (adler32-zlib) [ 3158.716814] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 3159.189659] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 3161.103189] Key type lgssc registered [ 3162.899811] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3231.789205] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3237.942069] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3262.449691] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3270.786141] Lustre: Mounted lustre-client [ 3275.961849] LustreError: 52832:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b965240b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3276.092201] Lustre: Unmounted lustre-client [ 3309.915508] Key type lgssc unregistered [ 3310.259299] LNet: 53236:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3310.276957] LNetError: 53236:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3310.301731] LNet: Removed LNI 192.168.201.5@tcp [ 3311.438617] Key type .llcrypt unregistered [ 3311.450314] Key type ._llcrypt unregistered [ 3335.893044] Key type ._llcrypt registered [ 3335.898619] Key type .llcrypt registered [ 3337.743637] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 17:34:34 (1773610474) [ 3342.578375] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3342.628912] alg: No test for adler32 (adler32-zlib) [ 3344.274990] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 3344.802589] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 3346.793060] Key type lgssc registered [ 3348.655089] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3449.191809] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3496.942253] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3502.421469] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3537.816616] Key type lgssc unregistered [ 3538.285151] LNet: 56643:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3538.298212] LNetError: 56643:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3538.324032] LNet: Removed LNI 192.168.201.5@tcp [ 3539.219592] Key type .llcrypt unregistered [ 3539.227720] Key type ._llcrypt unregistered [ 3560.888145] Key type ._llcrypt registered [ 3560.890364] Key type .llcrypt registered [ 3562.595901] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 17:38:19 (1773610699) [ 3567.443346] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3567.492428] alg: No test for adler32 (adler32-zlib) [ 3568.585515] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 3568.869832] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 3570.647181] Key type lgssc registered [ 3572.284850] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3670.192187] Lustre: Mounted lustre-client [ 3678.762716] LustreError: 59142:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9648193000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3678.965165] Lustre: Unmounted lustre-client [ 3739.052749] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 17:41:15 (1773610875) [ 3918.371785] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3939.930081] Lustre: Mounted lustre-client [ 3949.312758] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3949.331577] LustreError: 63119:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8b96465fb000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3949.365945] Lustre: 57630:0:(llite_lib.c:4158:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.105@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3959.779328] Lustre: Unmounted lustre-client [ 4015.455615] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 17:45:52 (1773611152) [ 4032.380662] Key type lgssc unregistered [ 4032.663704] LNet: 64332:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4032.673393] LNetError: 64332:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4032.692237] LNet: Removed LNI 192.168.201.5@tcp [ 4034.009070] Key type .llcrypt unregistered [ 4034.011489] Key type ._llcrypt unregistered [ 4052.674319] Key type ._llcrypt registered [ 4052.689780] Key type .llcrypt registered [ 4053.231705] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4053.266947] alg: No test for adler32 (adler32-zlib) [ 4054.564542] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 4055.221539] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 4057.040796] Key type lgssc registered [ 4059.027751] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4130.279422] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4136.093393] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4165.812921] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4168.737412] Lustre: Mounted lustre-client [ 4174.626925] LustreError: 66866:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b966064d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4174.830891] Lustre: Unmounted lustre-client [ 4209.705519] Key type lgssc unregistered [ 4210.148550] LNet: 67273:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4210.165377] LNetError: 67273:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4210.204573] LNet: Removed LNI 192.168.201.5@tcp [ 4211.255355] Key type .llcrypt unregistered [ 4211.258085] Key type ._llcrypt unregistered [ 4225.936059] Key type ._llcrypt registered [ 4225.943930] Key type .llcrypt registered [ 4226.868523] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4226.922829] alg: No test for adler32 (adler32-zlib) [ 4228.358437] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 4228.907376] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 4230.802769] Key type lgssc registered [ 4232.826602] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4391.609760] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4398.547697] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4430.131749] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4432.585273] Lustre: Mounted lustre-client [ 4437.737812] LustreError: 70227:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b965045c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4437.957442] Lustre: Unmounted lustre-client [ 4494.267783] Key type lgssc unregistered [ 4494.751056] LNet: 70636:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4494.756650] LNetError: 70636:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4494.800342] LNet: Removed LNI 192.168.201.5@tcp [ 4496.052556] Key type .llcrypt unregistered [ 4496.058785] Key type ._llcrypt unregistered [ 4523.361589] Key type ._llcrypt registered [ 4523.366505] Key type .llcrypt registered [ 4526.091563] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 17:54:21 (1773611661) [ 4529.959140] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4530.061201] alg: No test for adler32 (adler32-zlib) [ 4531.638234] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 4532.164292] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 4533.951909] Key type lgssc registered [ 4536.119741] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4609.840366] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4616.738380] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4649.826728] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4652.770889] Lustre: Mounted lustre-client [ 4661.533380] LustreError: 73768:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b966068a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4661.672057] Lustre: Unmounted lustre-client [ 4662.390094] Lustre: Mounted lustre-client [ 4666.937606] LustreError: 73845:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9648a84000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4667.056693] Lustre: Unmounted lustre-client [ 4701.057435] Key type lgssc unregistered [ 4701.471711] LNet: 74248:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4701.481518] LNetError: 74248:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4701.504271] LNet: Removed LNI 192.168.201.5@tcp [ 4702.549388] Key type .llcrypt unregistered [ 4702.556737] Key type ._llcrypt unregistered [ 4719.529727] Key type ._llcrypt registered [ 4719.536858] Key type .llcrypt registered [ 4720.302370] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4720.358788] alg: No test for adler32 (adler32-zlib) [ 4722.098281] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 4722.649412] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 4724.545796] Key type lgssc registered [ 4726.439956] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4798.929435] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4804.267783] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4831.665235] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4833.753378] Lustre: Mounted lustre-client [ 4839.392975] LustreError: 76795:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9645409800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4839.548486] Lustre: Unmounted lustre-client [ 4873.370697] Key type lgssc unregistered [ 4873.837740] LNet: 77200:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4873.851628] LNetError: 77200:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4873.881052] LNet: Removed LNI 192.168.201.5@tcp [ 4874.840150] Key type .llcrypt unregistered [ 4874.844806] Key type ._llcrypt unregistered [ 4897.112861] Key type ._llcrypt registered [ 4897.125224] Key type .llcrypt registered [ 4899.986703] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 18:00:35 (1773612035) [ 4905.333804] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4905.379118] alg: No test for adler32 (adler32-zlib) [ 4906.920327] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 4907.494277] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 4909.455233] Key type lgssc registered [ 4912.084968] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4982.207404] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4986.445535] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5010.982981] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5023.309029] Lustre: Mounted lustre-client [ 5035.671077] Lustre: lustre: root_squash is set to 0:0 [ 5035.722554] Lustre: lustre: nosquash_nids is cleared [ 5049.441083] Lustre: lustre: root_squash is set to 500:500 [ 5059.334880] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.5@tcp 192.168.0.[2,10]@tcp [ 5074.997407] Lustre: lustre: nosquash_nids set to 192.168.201.5/24@tcp [ 5079.281885] LustreError: 80690:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9650c6c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5079.425831] Lustre: Unmounted lustre-client [ 5110.978647] Key type lgssc unregistered [ 5111.297211] LNet: 81094:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5111.312962] LNetError: 81094:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5111.341352] LNet: Removed LNI 192.168.201.5@tcp [ 5112.337077] Key type .llcrypt unregistered [ 5112.340486] Key type ._llcrypt unregistered [ 5132.783812] Key type ._llcrypt registered [ 5132.785964] Key type .llcrypt registered [ 5134.587499] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 18:04:31 (1773612271) [ 5137.503877] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 5139.910590] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 18:04:36 (1773612276) [ 5143.217576] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5143.490242] alg: No test for adler32 (adler32-zlib) [ 5144.714284] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 5144.998034] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5146.775744] Key type lgssc registered [ 5148.745874] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5207.759966] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5213.361392] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5238.082201] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5240.008685] Lustre: lustre: root_squash is set to 500:500 [ 5240.061525] Lustre: lustre: nosquash_nids set to 192.168.201.5/24@tcp [ 5241.096746] Lustre: Mounted lustre-client [ 5248.159528] LustreError: 84569:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b964540f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5248.310364] Lustre: Unmounted lustre-client [ 5281.994814] Key type lgssc unregistered [ 5282.421417] LNet: 84975:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5282.435410] LNetError: 84975:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5282.461556] LNet: Removed LNI 192.168.201.5@tcp [ 5283.456541] Key type .llcrypt unregistered [ 5283.459086] Key type ._llcrypt unregistered [ 5305.340646] Key type ._llcrypt registered [ 5305.344287] Key type .llcrypt registered [ 5307.506957] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 5309.895778] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 18:07:26 (1773612446) [ 5333.890821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5333.922189] alg: No test for adler32 (adler32-zlib) [ 5335.149294] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 5335.497787] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5337.287405] Key type lgssc registered [ 5338.955843] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5435.587756] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5440.895802] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5465.827765] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5548.393267] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5553.285366] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5578.486916] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5592.153074] Lustre: Mounted lustre-client [ 5632.659433] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8b966064c800.ost_server_uuid 50 [ 5634.963643] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8b966064c800.ost_server_uuid in IDLE state after 0 sec [ 5636.044688] Lustre: Mounted lustre-client [ 5640.433235] LustreError: 91893:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9645835800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5640.565394] Lustre: Unmounted lustre-client [ 5642.862906] LustreError: 91914:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b966064c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5642.877393] LustreError: 91914:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5642.969333] Lustre: Unmounted lustre-client [ 5692.865754] Key type lgssc unregistered [ 5693.137977] LNet: 92467:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5693.151245] LNetError: 92467:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5694.184991] LNet: Removed LNI 192.168.201.5@tcp [ 5695.082222] Key type .llcrypt unregistered [ 5695.090207] Key type ._llcrypt unregistered [ 5728.397759] Key type ._llcrypt registered [ 5728.406945] Key type .llcrypt registered [ 5729.751463] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 18:14:26 (1773612866) [ 5749.357651] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5749.443885] alg: No test for adler32 (adler32-zlib) [ 5750.587986] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 5750.808416] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5752.503551] Key type lgssc registered [ 5753.697268] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5834.932630] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5838.723566] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5858.113497] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5859.883105] Lustre: Mounted lustre-client [ 5881.322611] Lustre: 93834:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773613003/real 1773613003] req@000000006fdf4e31 x1859767908701312/t0(0) o400->lustre-OST0000-osc-ffff8b9647df0000@192.168.201.105@tcp:28/4 lens 224/224 e 0 to 1 dl 1773613019 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5881.362590] Lustre: lustre-OST0000-osc-ffff8b9647df0000: Connection to lustre-OST0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5886.367140] Lustre: 93834:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773613008/real 1773613008] req@000000004b9d6073 x1859767908701824/t0(0) o400->lustre-OST0000-osc-ffff8b9647df0000@192.168.201.105@tcp:28/4 lens 224/224 e 0 to 1 dl 1773613024 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5890.527525] Lustre: 93834:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773613013/real 1773613013] req@000000002563ec30 x1859767908702336/t0(0) o400->lustre-OST0000-osc-ffff8b9647df0000@192.168.201.105@tcp:28/4 lens 224/224 e 0 to 1 dl 1773613029 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5891.623198] Lustre: lustre-OST0000-osc-ffff8b9647df0000: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [ 5895.647218] Lustre: 93833:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773613018/real 1773613018] req@000000003531d933 x1859767908702848/t0(0) o400->lustre-OST0000-osc-ffff8b9647df0000@192.168.201.105@tcp:28/4 lens 224/224 e 0 to 1 dl 1773613034 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5896.679253] Lustre: lustre-MDT0000-mdc-ffff8b9647df0000: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5913.055938] Lustre: 93833:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773613035/real 1773613035] req@00000000006a401e x1859767908705024/t0(0) o400->MGC192.168.201.105@tcp@192.168.201.105@tcp:26/25 lens 224/224 e 0 to 1 dl 1773613051 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5913.081304] LustreError: MGC192.168.201.105@tcp: Connection to MGS (at 192.168.201.105@tcp) was lost; in progress operations using this service will fail [ 5923.313676] Lustre: Evicted from MGS (at 192.168.201.105@tcp) after server handle changed from 0x12277202a7c20ab0 to 0x12277202a7c20d42 [ 5923.339364] Lustre: MGC192.168.201.105@tcp: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [ 5926.821375] Lustre: lustre-MDT0000-mdc-ffff8b9647df0000: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [ 5932.423963] LustreError: 97646:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9647df0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5932.495308] Lustre: Unmounted lustre-client [ 5960.716333] Key type lgssc unregistered [ 5960.963081] LNet: 98050:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5960.976214] LNetError: 98050:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5960.998247] LNet: Removed LNI 192.168.201.5@tcp [ 5961.621683] Key type .llcrypt unregistered [ 5961.624358] Key type ._llcrypt unregistered [ 5977.803080] Key type ._llcrypt registered [ 5977.805487] Key type .llcrypt registered [ 5979.422923] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 18:18:36 (1773613116) [ 5994.570676] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5994.670044] alg: No test for adler32 (adler32-zlib) [ 5995.910895] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 5996.257330] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5997.968729] Key type lgssc registered [ 5999.588399] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6039.778606] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6142.272595] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6146.095678] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6167.015269] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6168.681927] Lustre: Mounted lustre-client [ 6171.110382] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 6173.434911] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 18:21:50 (1773613310) [ 6545.014915] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6555.394163] Lustre: Unmounted lustre-client [ 6738.715849] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6744.508472] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6780.085712] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6853.790657] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 18:33:09 (1773613989) [ 7016.605597] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7023.741651] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7058.743293] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7060.147565] Lustre: Mounted lustre-client [ 7068.510075] LustreError: 116319:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9651b32800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7068.652798] Lustre: Unmounted lustre-client [ 7111.902954] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 18:37:27 (1773614247) [ 7265.728897] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7272.986354] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7307.864075] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7308.831365] Lustre: Mounted lustre-client [ 7316.458717] LustreError: 120401:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b966068b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7316.621924] Lustre: Unmounted lustre-client [ 7364.177241] Key type lgssc unregistered [ 7364.659825] LNet: 120807:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7364.676889] LNetError: 120807:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7364.695992] LNet: Removed LNI 192.168.201.5@tcp [ 7365.931464] Key type .llcrypt unregistered [ 7365.934962] Key type ._llcrypt unregistered [ 7396.694075] Key type ._llcrypt registered [ 7396.700707] Key type .llcrypt registered [ 7399.397627] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 18:42:14 (1773614534) [ 7405.563895] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7405.811469] alg: No test for adler32 (adler32-zlib) [ 7407.823820] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 7408.555241] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 7410.514819] Key type lgssc registered [ 7413.957961] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7491.473819] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7498.922602] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7530.422794] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7532.900099] Lustre: Mounted lustre-client [ 7549.285586] LustreError: 123925:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9650c6c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7549.496679] Lustre: Unmounted lustre-client [ 7588.245464] Key type lgssc unregistered [ 7588.791305] LNet: 124331:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7588.808863] LNetError: 124331:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7588.871391] LNet: Removed LNI 192.168.201.5@tcp [ 7590.179238] Key type .llcrypt unregistered [ 7590.184480] Key type ._llcrypt unregistered [ 7621.373871] Key type ._llcrypt registered [ 7621.376632] Key type .llcrypt registered [ 7624.212686] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 18:45:59 (1773614759) [ 7630.088296] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7630.153074] alg: No test for adler32 (adler32-zlib) [ 7631.727135] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 7632.323379] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 7634.240806] Key type lgssc registered [ 7636.976251] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7714.944412] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7721.061771] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7750.887185] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7752.963223] Lustre: Mounted lustre-client [ 7763.445889] Lustre: lustre-OST0000-osc-ffff8b96468cc800: Connection to lustre-OST0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7774.666780] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8b96468cc800.ost_server_uuid 50 [ 7777.421468] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 7792.407966] LustreError: 127890:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b96468cc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7792.568135] Lustre: Unmounted lustre-client [ 7822.127956] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 18:49:17 (1773614957) [ 7874.799283] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7881.688711] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7914.882033] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7950.593751] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7951.964342] Lustre: Mounted lustre-client [ 7957.022704] Lustre: lustre-OST0000-osc-ffff8b965240e000: Connection to lustre-OST0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7995.894947] LustreError: 130561:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b965240e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7996.011621] Lustre: Unmounted lustre-client [ 8067.828713] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 18:53:24 (1773615204) [ 8119.762166] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8127.262600] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8161.718607] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8197.312589] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8198.590404] Lustre: Mounted lustre-client [ 8203.766983] Lustre: lustre-OST0000-osc-ffff8b9648195800: Connection to lustre-OST0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8221.998479] LustreError: 133338:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9648195800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8222.010756] LustreError: 133338:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8222.168542] Lustre: Unmounted lustre-client [ 8298.867649] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 18:57:14 (1773615434) [ 8448.045404] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8455.074373] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8490.539108] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8594.346335] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8601.261762] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8634.988628] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8679.842443] Lustre: Mounted lustre-client [ 8737.696330] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8740.104636] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8745.730222] LustreError: 139992:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b966068e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8745.774579] LustreError: 139992:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8746.061755] Lustre: Unmounted lustre-client [ 8789.219360] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 19:05:25 (1773615925) [ 8841.504494] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8848.304220] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8879.643040] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8942.554074] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8989.205978] Lustre: Mounted lustre-client [ 9045.459713] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9048.493068] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [ 9063.999490] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9074.453443] Lustre: Unmounted lustre-client [ 9146.947689] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 19:11:23 (1773616283) [ 9195.881370] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9203.008453] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9234.192273] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9235.577657] Lustre: Mounted lustre-client [ 9273.303228] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9276.504757] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [ 9300.380392] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8b9648878800.ost_server_uuid 50 [ 9304.336758] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8b9648878800.ost_server_uuid in IDLE state after 0 sec [ 9310.220166] LustreError: 147044:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9648878800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9310.318930] Lustre: Unmounted lustre-client [ 9310.929028] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 9310.941784] Lustre: Skipped 3 previous similar messages [ 9311.204753] Lustre: Mounted lustre-client [ 9313.465293] LustreError: 147106:0:(sec.c:413:import_sec_validate_get()) import 0000000047e783a6 (NEW) with no sec [ 9318.862729] LustreError: 147125:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b96468ce800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9318.880515] LustreError: 147125:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9410.868855] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 19:15:46 (1773616546) [ 9461.765304] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9468.534334] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9498.417304] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9527.607589] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9528.508983] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 9528.754760] Lustre: Mounted lustre-client [ 9542.888383] LustreError: 149965:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b966068e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9543.109795] Lustre: Unmounted lustre-client [ 9543.114744] Lustre: Skipped 1 previous similar message [ 9585.562801] Key type lgssc unregistered [ 9585.897439] LNet: 150418:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9585.900793] LNetError: 150418:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9585.920293] LNet: Removed LNI 192.168.201.5@tcp [ 9586.819343] Key type .llcrypt unregistered [ 9586.822278] Key type ._llcrypt unregistered [ 9607.388815] Key type ._llcrypt registered [ 9607.390971] Key type .llcrypt registered [ 9609.134754] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 19:19:05 (1773616745) [ 9610.564333] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9610.677912] alg: No test for adler32 (adler32-zlib) [ 9611.859962] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 9612.212631] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 9613.911534] Key type lgssc registered [ 9615.705764] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9683.816289] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9688.742326] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9718.172804] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9743.860291] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9744.610593] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 9745.835992] Lustre: Mounted lustre-client [ 9749.681575] LustreError: lustre-MDT0001-mdc-ffff8b9643483000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 9749.748221] Lustre: lustre-MDT0001-mdc-ffff8b9643483000: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [ 9754.785911] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9756.791739] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9780.773181] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 9792.593394] LustreError: 154640:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9643483000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9792.717217] Lustre: Unmounted lustre-client [ 9822.809935] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 19:22:39 (1773616959) [ 9947.818723] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9953.139694] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9979.735869] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9980.314799] Lustre: Mounted lustre-client [10010.351861] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [10012.482354] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [10027.212617] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10037.703244] Lustre: Unmounted lustre-client [10085.938590] Key type lgssc unregistered [10086.436228] LNet: 159793:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10086.442792] LNetError: 159793:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10086.487081] LNet: Removed LNI 192.168.201.5@tcp [10087.508270] Key type .llcrypt unregistered [10087.515663] Key type ._llcrypt unregistered [10125.524736] Key type ._llcrypt registered [10125.527769] Key type .llcrypt registered [10127.315530] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 19:27:43 (1773617263) [10130.812306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10130.848412] alg: No test for adler32 (adler32-zlib) [10132.097959] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [10132.444149] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [10134.239952] Key type lgssc registered [10135.878990] Lustre: Echo OBD driver; http://www.lustre.org/ [10199.188962] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10204.310710] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10229.726921] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10231.724263] Lustre: Mounted lustre-client [10286.508467] LustreError: 163081:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b964887f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10286.628367] Lustre: Unmounted lustre-client [10335.566073] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10336.291863] Lustre: Mounted lustre-client [10374.729652] LustreError: 163740:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9660506800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10374.934703] Lustre: Unmounted lustre-client [10416.780981] Key type lgssc unregistered [10417.150032] LNet: 164151:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10417.161959] LNetError: 164151:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10417.183767] LNet: Removed LNI 192.168.201.5@tcp [10418.266691] Key type .llcrypt unregistered [10418.274621] Key type ._llcrypt unregistered [10437.843303] Key type ._llcrypt registered [10437.845346] Key type .llcrypt registered [10439.319330] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 19:32:56 (1773617576) [10442.535351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10442.568551] alg: No test for adler32 (adler32-zlib) [10443.639866] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [10443.893929] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [10445.575419] Key type lgssc registered [10447.004590] Lustre: Echo OBD driver; http://www.lustre.org/ [10496.871940] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10500.754692] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10519.005650] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10521.869955] Lustre: Mounted lustre-client [10542.115109] LustreError: 167421:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b966064c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10542.229946] Lustre: Unmounted lustre-client [10569.527927] Key type lgssc unregistered [10569.776960] LNet: 167825:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10569.787851] LNetError: 167825:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10569.800691] LNet: Removed LNI 192.168.201.5@tcp [10570.510620] Key type .llcrypt unregistered [10570.514080] Key type ._llcrypt unregistered [10581.959712] Key type ._llcrypt registered [10581.969624] Key type .llcrypt registered [10582.371448] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10582.383470] alg: No test for adler32 (adler32-zlib) [10583.458951] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [10583.673118] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [10585.359585] Key type lgssc registered [10586.611481] Lustre: Echo OBD driver; http://www.lustre.org/ [10628.351890] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10631.992697] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10648.049421] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10659.903788] Lustre: Mounted lustre-client [10665.345974] LustreError: 170397:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9647df4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10665.432877] Lustre: Unmounted lustre-client [10688.948168] Key type lgssc unregistered [10689.144275] LNet: 170802:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10689.152588] LNetError: 170802:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10689.170632] LNet: Removed LNI 192.168.201.5@tcp [10689.596623] Key type .llcrypt unregistered [10689.598873] Key type ._llcrypt unregistered [10703.155458] Key type ._llcrypt registered [10703.157755] Key type .llcrypt registered [10704.440664] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 19:37:21 (1773617841) [10706.482266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10706.562058] alg: No test for adler32 (adler32-zlib) [10707.617382] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [10707.798826] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [10709.465486] Key type lgssc registered [10710.710510] Lustre: Echo OBD driver; http://www.lustre.org/ [10752.701654] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10756.255790] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10772.284698] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10779.186754] Lustre: Mounted lustre-client [10794.546371] LustreError: 174065:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9644228000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10794.627182] Lustre: Unmounted lustre-client [10819.338137] Key type lgssc unregistered [10819.607674] LNet: 174468:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10819.619214] LNetError: 174468:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10819.642262] LNet: Removed LNI 192.168.201.5@tcp [10820.238450] Key type .llcrypt unregistered [10820.240155] Key type ._llcrypt unregistered [10830.170749] Key type ._llcrypt registered [10830.178381] Key type .llcrypt registered [10830.526430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10830.541720] alg: No test for adler32 (adler32-zlib) [10831.598277] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [10831.829362] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [10833.527180] Key type lgssc registered [10834.575723] Lustre: Echo OBD driver; http://www.lustre.org/ [10868.761331] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10870.904548] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10883.301460] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10884.720110] Lustre: Mounted lustre-client [10892.735200] LustreError: 177040:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9648879000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10892.789870] Lustre: Unmounted lustre-client [10915.834886] Key type lgssc unregistered [10916.012333] LNet: 177443:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10916.020602] LNetError: 177443:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10916.036773] LNet: Removed LNI 192.168.201.5@tcp [10916.373634] Key type .llcrypt unregistered [10916.375560] Key type ._llcrypt unregistered [10925.046897] Key type ._llcrypt registered [10925.048489] Key type .llcrypt registered [10925.842070] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 19:41:03 (1773618063) [10937.260237] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10937.272746] alg: No test for adler32 (adler32-zlib) [10938.229682] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [10938.391730] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [10940.023426] Key type lgssc registered [10940.677483] Lustre: Echo OBD driver; http://www.lustre.org/ [10988.381893] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10990.567342] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11003.587285] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11032.263628] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 19:42:50 (1773618170) [11049.200406] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11051.531344] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11062.052707] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11064.379376] Lustre: Mounted lustre-client [11070.627676] LustreError: 183950:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b966064b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11070.688371] Lustre: Unmounted lustre-client [11087.417191] Key type lgssc unregistered [11087.605730] LNet: 184352:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11087.612895] LNetError: 184352:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11087.621958] LNet: Removed LNI 192.168.201.5@tcp [11088.067777] Key type .llcrypt unregistered [11088.070540] Key type ._llcrypt unregistered [11096.252501] Key type ._llcrypt registered [11096.255465] Key type .llcrypt registered [11097.082562] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [11098.245411] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 19:43:55 (1773618235) [11108.753760] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11108.780588] alg: No test for adler32 (adler32-zlib) [11109.703731] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [11109.828860] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [11111.440530] Key type lgssc registered [11112.165505] Lustre: Echo OBD driver; http://www.lustre.org/ [11170.744293] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11173.229294] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11186.686731] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [11198.951209] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [11200.382818] Lustre: Mounted lustre-client [11213.881807] LustreError: 189889:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9646f6d800: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [11213.981180] Lustre: Unmounted lustre-client [11309.652619] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11312.070899] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11325.234659] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11371.732888] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 19:48:29 (1773618509) [11372.781279] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [11374.013389] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 19:48:31 (1773618511) [11409.312754] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11411.608993] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11443.801142] Key type lgssc unregistered [11444.006643] LNet: 196072:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11444.012542] LNetError: 196072:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11444.028206] LNet: Removed LNI 192.168.201.5@tcp [11444.485616] Key type .llcrypt unregistered [11444.488373] Key type ._llcrypt unregistered [11453.850052] Key type ._llcrypt registered [11453.855941] Key type .llcrypt registered [11454.710688] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 19:49:52 (1773618592) [11495.233434] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11495.254200] alg: No test for adler32 (adler32-zlib) [11496.244467] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [11496.409515] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [11498.042146] Key type lgssc registered [11498.791354] Lustre: Echo OBD driver; http://www.lustre.org/ [11521.634104] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11523.647155] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11546.885113] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11560.590118] Key type lgssc unregistered [11560.771753] LNet: 199705:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11560.777067] LNetError: 199705:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11560.787538] LNet: Removed LNI 192.168.201.5@tcp [11561.174583] Key type .llcrypt unregistered [11561.175884] Key type ._llcrypt unregistered [11569.017649] Key type ._llcrypt registered [11569.019030] Key type .llcrypt registered [11569.680494] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 19:51:47 (1773618707) [11571.044946] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11571.055606] alg: No test for adler32 (adler32-zlib) [11571.980440] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [11572.104129] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [11573.703174] Key type lgssc registered [11574.339397] Lustre: Echo OBD driver; http://www.lustre.org/ [11600.962806] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11602.775513] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11614.802089] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11616.297832] Lustre: Mounted lustre-client [11626.467992] Lustre: lustre-MDT0000-mdc-ffff8b96605b2800: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [11631.591543] Lustre: lustre-MDT0001-mdc-ffff8b96605b2800: Connection to lustre-MDT0001 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [11646.950223] LustreError: MGC192.168.201.105@tcp: Connection to MGS (at 192.168.201.105@tcp) was lost; in progress operations using this service will fail [11646.962731] Lustre: Evicted from MGS (at 192.168.201.105@tcp) after server handle changed from 0x6adb96f0e9026e1 to 0x6adb96f0e904041 [11646.970822] Lustre: MGC192.168.201.105@tcp: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [11666.128182] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11667.427358] LustreError: lustre-MDT0000-mdc-ffff8b96605b2800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11667.459394] Lustre: lustre-MDT0000-mdc-ffff8b96605b2800: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [11669.145745] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 2 sec [11670.944584] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11673.074439] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 1 sec [11674.282540] LustreError: 204132:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b96605b2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11674.367739] Lustre: Unmounted lustre-client [11694.710345] Key type lgssc unregistered [11694.932508] LNet: 204536:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11694.937844] LNetError: 204536:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11694.954440] LNet: Removed LNI 192.168.201.5@tcp [11695.494932] Key type .llcrypt unregistered [11695.496939] Key type ._llcrypt unregistered [11703.855827] Key type ._llcrypt registered [11703.858306] Key type .llcrypt registered [11704.679657] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 19:54:02 (1773618842) [11706.028879] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11706.053523] alg: No test for adler32 (adler32-zlib) [11707.008369] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [11707.139227] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [11708.759169] Key type lgssc registered [11709.332052] Lustre: Echo OBD driver; http://www.lustre.org/ [11738.616291] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11741.211355] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11775.673553] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11778.192948] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11798.519437] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11810.335938] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [11839.642051] Key type lgssc unregistered [11839.771438] LNet: 209642:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11839.777585] LNetError: 209642:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11839.788315] LNet: Removed LNI 192.168.201.5@tcp [11840.162052] Key type .llcrypt unregistered [11840.163874] Key type ._llcrypt unregistered [11868.174742] Key type ._llcrypt registered [11868.176432] Key type .llcrypt registered [11868.948201] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 19:56:46 (1773619006) [11890.597413] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11890.604870] alg: No test for adler32 (adler32-zlib) [11891.527456] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [11891.673655] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [11893.335343] Key type lgssc registered [11894.109626] Lustre: Echo OBD driver; http://www.lustre.org/ [11936.076104] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11938.123338] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11949.768224] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11977.253566] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 19:58:35 (1773619115) [11981.076735] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 19:58:38 (1773619118) [11996.430579] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11998.280471] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12007.412513] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12014.051719] Lustre: Mounted lustre-client [12021.615363] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [12022.874330] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [12023.718313] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [12024.682592] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [12029.412392] Lustre: lustre-MDT0000-mdc-ffff8b9645393800: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [12044.772688] LustreError: MGC192.168.201.105@tcp: Connection to MGS (at 192.168.201.105@tcp) was lost; in progress operations using this service will fail [12044.796616] Lustre: Evicted from MGS (at 192.168.201.105@tcp) after server handle changed from 0xec1e494c165f3f6c to 0xec1e494c165f40ed [12044.805150] Lustre: MGC192.168.201.105@tcp: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [12057.978485] Lustre: lustre-MDT0000-mdc-ffff8b9645393800: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [12060.068688] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12060.909761] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [12063.336333] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [12068.326325] Lustre: lustre-MDT0000-mdc-ffff8b9645393800: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [12083.680206] Lustre: 211536:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773619206/real 1773619206] req@0000000062a335ce x1859774348019456/t0(0) o400->MGC192.168.201.105@tcp@192.168.201.105@tcp:26/25 lens 224/224 e 0 to 1 dl 1773619222 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [12083.689855] LustreError: MGC192.168.201.105@tcp: Connection to MGS (at 192.168.201.105@tcp) was lost; in progress operations using this service will fail [12083.709464] Lustre: Evicted from MGS (at 192.168.201.105@tcp) after server handle changed from 0xec1e494c165f40ed to 0xec1e494c165f44f9 [12083.716566] Lustre: MGC192.168.201.105@tcp: Connection restored to 192.168.201.105@tcp (at 192.168.201.105@tcp) [12086.252402] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12086.989184] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [12088.812924] LustreError: 219779:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9645393800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12088.864689] Lustre: Unmounted lustre-client [12130.357370] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12132.395399] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12141.810315] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12147.198775] Lustre: Mounted lustre-client [12152.737301] LustreError: 221403:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9644b07000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12152.788165] Lustre: Unmounted lustre-client [12180.183695] Key type lgssc unregistered [12180.317424] LNet: 221806:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12180.319790] LNetError: 221806:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12180.328980] LNet: Removed LNI 192.168.201.5@tcp [12180.637327] Key type .llcrypt unregistered [12180.638519] Key type ._llcrypt unregistered [12187.717334] Key type ._llcrypt registered [12187.719037] Key type .llcrypt registered [12188.320035] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 20:02:06 (1773619326) [12190.209154] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12190.247418] alg: No test for adler32 (adler32-zlib) [12191.164902] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12191.285224] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [12192.887151] Key type lgssc registered [12193.470895] Lustre: Echo OBD driver; http://www.lustre.org/ [12216.656793] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12218.519068] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12227.318344] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12233.725905] Lustre: Mounted lustre-client [12237.130487] LustreError: 224974:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b964742f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12237.179190] Lustre: Unmounted lustre-client [12274.816656] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12276.413518] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12284.357310] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12295.163425] Lustre: Mounted lustre-client [12301.677871] LustreError: 226569:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9645180000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12301.720583] Lustre: Unmounted lustre-client [12340.077669] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12341.635788] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12349.627097] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12355.068122] Lustre: Mounted lustre-client [12355.522338] LustreError: 228181:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9646e90000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12355.566623] Lustre: Unmounted lustre-client [12374.688393] Key type lgssc unregistered [12374.851523] LNet: 228585:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12374.855824] LNetError: 228585:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12374.875747] LNet: Removed LNI 192.168.201.5@tcp [12375.236145] Key type .llcrypt unregistered [12375.237526] Key type ._llcrypt unregistered [12382.678651] Key type ._llcrypt registered [12382.681021] Key type .llcrypt registered [12383.448316] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 20:05:21 (1773619521) [12385.082251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12385.149153] alg: No test for adler32 (adler32-zlib) [12386.077139] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12386.196361] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [12387.815168] Key type lgssc registered [12388.376110] Lustre: Echo OBD driver; http://www.lustre.org/ [12409.433678] Key type lgssc unregistered [12409.585351] LNet: 230899:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12409.588312] LNetError: 230899:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12409.595321] LNet: Removed LNI 192.168.201.5@tcp [12409.884053] Key type .llcrypt unregistered [12409.885315] Key type ._llcrypt unregistered [12420.840112] Key type ._llcrypt registered [12420.842503] Key type .llcrypt registered [12421.102154] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12421.110410] alg: No test for adler32 (adler32-zlib) [12422.012956] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12422.127970] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [12423.727343] Key type lgssc registered [12424.232413] Lustre: Echo OBD driver; http://www.lustre.org/ [12461.423656] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12463.319722] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12473.759742] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12507.199141] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [12508.051764] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 20:07:25 (1773619645) [12522.350443] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12524.143975] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12532.033771] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12541.218380] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [12542.468463] Lustre: Mounted lustre-client [12547.560797] Lustre: lustre-OST0001-osc-ffff8b9647df0800: Connection to lustre-OST0001 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [12550.641537] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12560.957138] Lustre: Unmounted lustre-client [12585.539869] Key type lgssc unregistered [12585.681630] LNet: 237791:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12585.686288] LNetError: 237791:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12585.696391] LNet: Removed LNI 192.168.201.5@tcp [12585.998807] Key type .llcrypt unregistered [12586.000246] Key type ._llcrypt unregistered [12598.340405] Key type ._llcrypt registered [12598.341865] Key type .llcrypt registered [12598.913974] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 20:08:56 (1773619736) [12604.188151] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12604.202163] alg: No test for adler32 (adler32-zlib) [12605.077171] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12605.172951] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [12606.759237] Key type lgssc registered [12607.227790] Lustre: Echo OBD driver; http://www.lustre.org/ [12626.967967] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12628.366667] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12640.193526] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 20:09:38 (1773619778) [12650.478496] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12651.811327] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12659.112144] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12660.329291] Lustre: Mounted lustre-client [12665.129555] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12668.129545] Lustre: Unmounted lustre-client [12720.569871] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12722.114441] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12755.488045] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12766.207449] Lustre: Mounted lustre-client [12766.776212] LustreError: 244853:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b96504cf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12766.813504] Lustre: Unmounted lustre-client [12793.594602] Key type lgssc unregistered [12793.716634] LNet: 245258:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12793.720919] LNetError: 245258:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12793.729164] LNet: Removed LNI 192.168.201.5@tcp [12794.015682] Key type .llcrypt unregistered [12794.017265] Key type ._llcrypt unregistered [12805.129297] Key type ._llcrypt registered [12805.132953] Key type .llcrypt registered [12805.343189] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12805.357733] alg: No test for adler32 (adler32-zlib) [12806.243332] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12806.349140] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [12807.943174] Key type lgssc registered [12808.431913] Lustre: Echo OBD driver; http://www.lustre.org/ [12840.981625] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12842.382622] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12850.777543] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12869.139021] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12869.710060] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 20:13:27 (1773620007) [12880.672626] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12882.092966] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12888.337893] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12899.823777] Lustre: Mounted lustre-client [12900.748525] LustreError: 251291:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9645393000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12900.775336] Lustre: Unmounted lustre-client [12918.351868] Key type lgssc unregistered [12918.484601] LNet: 251695:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12918.487874] LNetError: 251695:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12918.498755] LNet: Removed LNI 192.168.201.5@tcp [12918.752715] Key type .llcrypt unregistered [12918.754606] Key type ._llcrypt unregistered [12924.743119] Key type ._llcrypt registered [12924.744553] Key type .llcrypt registered [12925.215337] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12925.848278] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 20:14:23 (1773620063) [12929.230391] Key type .llcrypt unregistered [12929.231969] Key type ._llcrypt unregistered [12932.330929] Key type ._llcrypt registered [12932.332672] Key type .llcrypt registered [12932.564758] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12932.581379] alg: No test for adler32 (adler32-zlib) [12933.461429] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12933.564729] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [12935.159152] Key type lgssc registered [12935.610861] Lustre: Echo OBD driver; http://www.lustre.org/ [12954.767154] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12965.365464] Lustre: Mounted lustre-client [12965.992698] LustreError: 254709:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9646e92000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12966.028949] Lustre: Unmounted lustre-client [12984.307826] Key type lgssc unregistered [12984.443847] LNet: 255110:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12984.451967] LNetError: 255110:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12984.461773] LNet: Removed LNI 192.168.201.5@tcp [12984.737307] Key type .llcrypt unregistered [12984.738313] Key type ._llcrypt unregistered [12991.017194] Key type ._llcrypt registered [12991.018589] Key type .llcrypt registered [12991.567558] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 20:15:29 (1773620129) [12992.488231] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12992.521896] alg: No test for adler32 (adler32-zlib) [12993.400402] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12993.502095] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [12995.095174] Key type lgssc registered [12995.554379] Lustre: Echo OBD driver; http://www.lustre.org/ [13057.078923] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13069.377046] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13070.988194] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13072.194994] Lustre: Mounted lustre-client [13072.759721] LustreError: 258149:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b964742b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13072.792124] Lustre: Unmounted lustre-client [13091.331640] Key type lgssc unregistered [13091.476896] LNet: 258552:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13091.482203] LNetError: 258552:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13091.490091] LNet: Removed LNI 192.168.201.5@tcp [13091.805238] Key type .llcrypt unregistered [13091.807126] Key type ._llcrypt unregistered [13098.908334] Key type ._llcrypt registered [13098.910489] Key type .llcrypt registered [13099.636919] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 20:17:17 (1773620237) [13100.759727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13100.781323] alg: No test for adler32 (adler32-zlib) [13101.693149] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [13101.819683] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [13103.447237] Key type lgssc registered [13104.067154] Lustre: Echo OBD driver; http://www.lustre.org/ [13126.424638] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13128.010173] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13136.515494] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13142.004696] Lustre: Mounted lustre-client [13147.106144] Lustre: lustre-MDT0000-mdc-ffff8b965240b000: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [13149.851146] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13149.862484] LustreError: 261614:0:(file.c:6089:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13149.986760] LustreError: 261633:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b965240b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13149.988294] LustreError: MGC192.168.201.105@tcp: Connection to MGS (at 192.168.201.105@tcp) was lost; in progress operations using this service will fail [13150.023460] Lustre: Unmounted lustre-client [13162.026279] Key type lgssc unregistered [13162.157646] LNet: 262027:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13162.160869] LNetError: 262027:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13162.172351] LNet: Removed LNI 192.168.201.5@tcp [13162.470565] Key type .llcrypt unregistered [13162.472149] Key type ._llcrypt unregistered [13168.667673] Key type ._llcrypt registered [13168.669711] Key type .llcrypt registered [13169.217459] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 20:18:27 (1773620307) [13170.124956] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13170.165093] alg: No test for adler32 (adler32-zlib) [13171.060224] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [13171.158763] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [13172.759173] Key type lgssc registered [13173.244363] Lustre: Echo OBD driver; http://www.lustre.org/ [13192.859390] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13194.238425] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13201.183081] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13211.636130] Lustre: Mounted lustre-client [13216.739744] Lustre: lustre-MDT0001-mdc-ffff8b965240b000: Connection to lustre-MDT0001 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [13219.460284] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13219.866519] LustreError: 265130:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b965240b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13219.900580] Lustre: Unmounted lustre-client [13231.811754] Key type lgssc unregistered [13231.944113] LNet: 265525:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13231.947429] LNetError: 265525:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13231.957184] LNet: Removed LNI 192.168.201.5@tcp [13232.227752] Key type .llcrypt unregistered [13232.228809] Key type ._llcrypt unregistered [13238.505433] Key type ._llcrypt registered [13238.506972] Key type .llcrypt registered [13239.083281] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 20:19:37 (1773620377) [13246.339310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13246.354802] alg: No test for adler32 (adler32-zlib) [13247.235973] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [13247.335458] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [13248.927194] Key type lgssc registered [13249.444217] Lustre: Echo OBD driver; http://www.lustre.org/ [13289.163779] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13290.354496] Lustre: Mounted lustre-client [13306.850818] Lustre: lustre-MDT0001-mdc-ffff8b9650c6f000: Connection to lustre-MDT0001 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [13311.978151] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13322.235886] Lustre: Unmounted lustre-client [13339.394513] Key type lgssc unregistered [13339.517566] LNet: 271262:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13339.520232] LNetError: 271262:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13339.530313] LNet: Removed LNI 192.168.201.5@tcp [13339.787562] Key type .llcrypt unregistered [13339.788437] Key type ._llcrypt unregistered [13344.740962] Key type ._llcrypt registered [13344.741958] Key type .llcrypt registered [13345.248268] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 20:21:23 (1773620483) [13345.831987] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13346.402848] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 20:21:24 (1773620484) [13346.967101] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13347.540375] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 20:21:25 (1773620485) [13348.103592] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13348.686432] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 20:21:26 (1773620486) [13349.214791] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13349.735610] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 20:21:27 (1773620487) [13350.266735] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13350.858246] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 20:21:28 (1773620488) [13353.698584] Key type .llcrypt unregistered [13353.700029] Key type ._llcrypt unregistered [13355.969332] Key type ._llcrypt registered [13355.970582] Key type .llcrypt registered [13356.160603] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13356.172287] alg: No test for adler32 (adler32-zlib) [13357.039126] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [13357.134023] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [13358.727151] Key type lgssc registered [13359.111219] Lustre: Echo OBD driver; http://www.lustre.org/ [13385.144203] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13386.377796] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13393.718521] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13394.885753] Lustre: Mounted lustre-client [13395.363612] LustreError: 276857:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b96481f2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13395.395128] Lustre: Unmounted lustre-client [13417.489288] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 20:22:35 (1773620555) [13427.729694] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13428.996164] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13448.146723] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13448.338959] Lustre: Mounted lustre-client [13448.690127] LustreError: 278969:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8b9644628800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13448.717401] Lustre: Unmounted lustre-client [13478.315217] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 20:23:36 (1773620616) [13506.655319] LNet: 1 local NIs in recovery (showing 1): 192.168.201.5@tcp