[ 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 470027903 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 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, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.003296] x2apic enabled [ 0.004006] Switched APIC routing to physical x2apic. [ 0.005017] kvm-guest: setup PV IPIs [ 0.007927] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009017] pid_max: default: 32768 minimum: 301 [ 0.010158] LSM: Security Framework initializing [ 0.011072] Yama: becoming mindful. [ 0.012058] SELinux: Initializing. [ 0.013076] *** VALIDATE selinux *** [ 0.021309] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026745] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027166] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028129] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029132] *** VALIDATE tmpfs *** [ 0.031360] *** VALIDATE proc *** [ 0.032283] *** VALIDATE cgroup *** [ 0.033016] *** VALIDATE cgroup2 *** [ 0.034304] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036053] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039019] Spectre V2 : User space: Vulnerable [ 0.040013] Speculative Store Bypass: Vulnerable [ 0.043296] debug: unmapping init [mem 0xffffffffb6a59000-0xffffffffb6a60fff] [ 0.045224] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046769] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047035] ... version: 2 [ 0.048017] ... bit width: 48 [ 0.049015] ... generic registers: 4 [ 0.050016] ... value mask: 0000ffffffffffff [ 0.051021] ... max period: 00007fffffffffff [ 0.052018] ... fixed-purpose events: 3 [ 0.053015] ... event mask: 000000070000000f [ 0.054361] rcu: Hierarchical SRCU implementation. [ 0.056534] smp: Bringing up secondary CPUs ... [ 0.057668] x86: Booting SMP configuration: [ 0.058037] .... node #0, CPUs: #1 #2 #3 [ 0.061420] smp: Brought up 1 node, 4 CPUs [ 0.063014] smpboot: Max logical packages: 1 [ 0.064021] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.153279] node 0 deferred pages initialised in 87ms [ 0.156157] devtmpfs: initialized [ 0.157217] x86/mm: Memory block size: 128MB [ 0.159209] gcov: version magic: 0x41383552 [ 0.161186] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.162099] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.163244] pinctrl core: initialized pinctrl subsystem [ 0.164208] [ 0.164759] ************************************************************* [ 0.165018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.166018] ** ** [ 0.167014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.168014] ** ** [ 0.169014] ** This means that this kernel is built to expose internal ** [ 0.170018] ** IOMMU data structures, which may compromise security on ** [ 0.171016] ** your system. ** [ 0.172020] ** ** [ 0.173016] ** If you see this message and you are not debugging the ** [ 0.174019] ** kernel, report this immediately to your vendor! ** [ 0.175017] ** ** [ 0.176020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.177017] ************************************************************* [ 0.178704] NET: Registered protocol family 16 [ 0.179475] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.180074] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.181086] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.182497] cpuidle: using governor menu [ 0.184804] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.187619] PCI: Using configuration type 1 for base access [ 0.190119] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.202057] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.203014] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.205087] cryptd: max_cpu_qlen set to 1000 [ 0.207247] ACPI: Added _OSI(Module Device) [ 0.208016] ACPI: Added _OSI(Processor Device) [ 0.209015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.210020] ACPI: Added _OSI(Processor Aggregator Device) [ 0.213643] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.220312] ACPI: Interpreter enabled [ 0.222086] ACPI: PM: (supports S0 S3 S4 S5) [ 0.223016] ACPI: Using IOAPIC for interrupt routing [ 0.225115] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.229386] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.240640] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.243114] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.246024] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.249170] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.255780] acpiphp: Slot [2] registered [ 0.257200] acpiphp: Slot [5] registered [ 0.258228] acpiphp: Slot [6] registered [ 0.260173] acpiphp: Slot [3] registered [ 0.261111] acpiphp: Slot [4] registered [ 0.262113] acpiphp: Slot [7] registered [ 0.264141] acpiphp: Slot [8] registered [ 0.265111] acpiphp: Slot [9] registered [ 0.268164] acpiphp: Slot [10] registered [ 0.269140] acpiphp: Slot [11] registered [ 0.270278] acpiphp: Slot [12] registered [ 0.272113] acpiphp: Slot [13] registered [ 0.273113] acpiphp: Slot [14] registered [ 0.274123] acpiphp: Slot [15] registered [ 0.276144] acpiphp: Slot [16] registered [ 0.277130] acpiphp: Slot [17] registered [ 0.279122] acpiphp: Slot [18] registered [ 0.280129] acpiphp: Slot [19] registered [ 0.282128] acpiphp: Slot [20] registered [ 0.283114] acpiphp: Slot [21] registered [ 0.285117] acpiphp: Slot [22] registered [ 0.286288] acpiphp: Slot [23] registered [ 0.288138] acpiphp: Slot [24] registered [ 0.289147] acpiphp: Slot [25] registered [ 0.291174] acpiphp: Slot [26] registered [ 0.293221] acpiphp: Slot [27] registered [ 0.294149] acpiphp: Slot [28] registered [ 0.296109] acpiphp: Slot [29] registered [ 0.297097] acpiphp: Slot [30] registered [ 0.298141] acpiphp: Slot [31] registered [ 0.299078] PCI host bridge to bus 0000:00 [ 0.301043] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.303032] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.305035] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.308022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.310035] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.313032] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.315226] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.319319] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.322437] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.330015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.335062] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.337033] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.339020] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.342020] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.344522] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.347829] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.350063] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.353079] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.358020] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.368113] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.373024] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.379089] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.384017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.391019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.408018] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.417365] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.423021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.428019] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.440023] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.450875] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.453217] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.456428] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.459413] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.461191] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.466094] iommu: Default domain type: Passthrough [ 0.467448] SCSI subsystem initialized [ 0.469157] ACPI: bus type USB registered [ 0.471153] usbcore: registered new interface driver usbfs [ 0.473121] usbcore: registered new interface driver hub [ 0.475092] usbcore: registered new device driver usb [ 0.476200] pps_core: LinuxPPS API ver. 1 registered [ 0.478016] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.481067] PTP clock support registered [ 0.483154] EDAC MC: Ver: 3.0.0 [ 0.485183] PCI: Using ACPI for IRQ routing [ 0.486452] NetLabel: Initializing [ 0.487024] NetLabel: domain hash size = 128 [ 0.488014] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.491125] NetLabel: unlabeled traffic allowed by default [ 0.493047] vgaarb: loaded [ 0.494285] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.495013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.499305] clocksource: Switched to clocksource kvm-clock [ 0.605322] VFS: Disk quotas dquot_6.6.0 [ 0.607014] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.609719] *** VALIDATE ramfs *** [ 0.610974] *** VALIDATE hugetlbfs *** [ 0.612551] pnp: PnP ACPI init [ 0.615025] pnp: PnP ACPI: found 6 devices [ 0.633972] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.637075] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.639189] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.641482] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.643879] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.646053] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.648780] NET: Registered protocol family 2 [ 0.651162] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.655125] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.658646] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.663736] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.666882] TCP: Hash tables configured (established 65536 bind 65536) [ 0.669567] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.672254] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.674843] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.677479] NET: Registered protocol family 1 [ 0.680033] RPC: Registered named UNIX socket transport module. [ 0.682343] RPC: Registered udp transport module. [ 0.684047] RPC: Registered tcp transport module. [ 0.685611] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.687703] NET: Registered protocol family 44 [ 0.689266] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.691192] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.693145] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.695229] PCI: CLS 0 bytes, default 64 [ 0.696636] Unpacking initramfs... [ 2.114777] debug: unmapping init [mem 0xffff9163bcc64000-0xffff9163bffcffff] [ 2.118884] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.121197] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.124092] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.645131] Initialise system trusted keyrings [ 2.646871] Key type blacklist registered [ 2.648718] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.657320] zbud: loaded [ 2.659823] *** VALIDATE nfs *** [ 2.660858] *** VALIDATE nfs4 *** [ 2.662454] pstore: using deflate compression [ 2.666548] Platform Keyring initialized [ 2.806959] NET: Registered protocol family 38 [ 2.813244] Key type asymmetric registered [ 2.814817] Asymmetric key parser 'x509' registered [ 2.816709] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.819774] io scheduler mq-deadline registered [ 2.821486] io scheduler kyber registered [ 2.823120] io scheduler bfq registered [ 2.825134] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.828430] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.831253] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.834081] ACPI: Power Button [PWRF] [ 2.840120] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.847906] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.858440] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.885987] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.914022] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.918949] Non-volatile memory driver v1.3 [ 2.920453] Linux agpgart interface v0.103 [ 2.949452] virtio_blk virtio1: [vda] 134360 512-byte logical blocks (68.8 MB/65.6 MiB) [ 2.952135] vda: detected capacity change from 0 to 68792320 [ 2.965868] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.968692] vdb: detected capacity change from 0 to 1073741824 [ 2.974618] libphy: Fixed MDIO Bus: probed [ 2.982722] usbcore: registered new interface driver usbserial_generic [ 2.985396] usbserial: USB Serial support registered for generic [ 2.987185] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.991477] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.992963] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.995126] mousedev: PS/2 mouse device common for all mice [ 2.998082] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.999427] rtc_cmos 00:05: RTC can wake from S4 [ 3.006201] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.008492] rtc_cmos 00:05: registered as rtc0 [ 3.014974] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.019370] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.019565] intel_pstate: CPU model not supported [ 3.025351] hid: raw HID events driver (C) Jiri Kosina [ 3.027197] usbcore: registered new interface driver usbhid [ 3.028967] usbhid: USB HID core driver [ 3.030238] drop_monitor: Initializing network drop monitor service [ 3.032316] Initializing XFRM netlink socket [ 3.034819] NET: Registered protocol family 10 [ 3.038425] Segment Routing with IPv6 [ 3.039887] NET: Registered protocol family 17 [ 3.041868] mpls_gso: MPLS GSO support [ 3.048876] RAS: Correctable Errors collector initialized. [ 3.051153] AVX version of gcm_enc/dec engaged. [ 3.052885] AES CTR mode by8 optimization enabled [ 3.129801] sched_clock: Marking stable (3129776844, 0)->(4037013006, -907236162) [ 3.133381] registered taskstats version 1 [ 3.135373] Loading compiled-in X.509 certificates [ 3.137463] zswap: loaded using pool lzo/zbud [ 3.162186] Key type big_key registered [ 3.174610] Key type encrypted registered [ 3.176239] ima: No TPM chip found, activating TPM-bypass! [ 3.178532] ima: Allocated hash algorithm: sha1 [ 3.180217] ima: No architecture policies found [ 3.181842] evm: Initialising EVM extended attributes: [ 3.183644] evm: security.selinux [ 3.184989] evm: security.ima [ 3.186034] evm: security.capability [ 3.187328] evm: HMAC attrs: 0x1 [ 3.189699] rtc_cmos 00:05: setting system clock to 2026-01-30 06:33:44 UTC (1769754824) [ 3.195660] debug: unmapping init [mem 0xffffffffb7a03000-0xffffffffb7bfffff] [ 3.198497] debug: unmapping init [mem 0xffffffffb6782000-0xffffffffb6a58fff] [ 3.203238] Write protecting the kernel read-only data: 28672k [ 3.206593] debug: unmapping init [mem 0xffffffffb4e03000-0xffffffffb4ffffff] [ 3.209187] debug: unmapping init [mem 0xffffffffb5714000-0xffffffffb57fffff] [ 3.238316] 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.245638] systemd[1]: Detected virtualization kvm. [ 3.247565] systemd[1]: Detected architecture x86-64. [ 3.248996] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.275928] systemd[1]: No hostname configured. [ 3.277817] systemd[1]: Set hostname to . [ 3.279981] random: systemd: uninitialized urandom read (16 bytes read) [ 3.282531] systemd[1]: Initializing machine ID from random generator. [ 3.328115] random: ln: uninitialized urandom read (6 bytes read) [ 3.413518] random: systemd: uninitialized urandom read (16 bytes read) [ 3.415547] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.421449] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.425410] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Slices. [ OK ] Reached target Swap. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Journal Service... Starting Setup Virtual Console... [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.090245] device-mapper: uevent: version 1.0.3 [ 4.092234] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.893444] virtio_net virtio0 ens2: renamed from eth0 [ 4.906268] scsi host0: ata_piix [ 4.925613] scsi host1: ata_piix [ 4.927878] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.930440] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.381807] dracut-initqueue[588]: RTNETLINK answers: File exists [ 9.692742] random: crng init done [ 9.694187] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.168992] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] 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... [ 11.284960] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.528030] SELinux: Disabled at runtime. [ 11.583896] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.592468] systemd[1]: Detected virtualization kvm. [ 11.594532] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.049395] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.052453] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.055835] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.059146] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.063236] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.069025] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.073218] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on Process Core Dump Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting POSIX Message Queue File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Reached target RPC Port Mapper. Mounting Kernel Debug File System... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Activating swap /dev/disk/by-label/SWAP... Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-getty.slice. [ 12.234294] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Apply Kernel Variables... [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. 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. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 12.584024] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.942654] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.028094] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.149215] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.199664] EDAC sbridge: Ver: 1.1.2 [ 14.226787] Key type dns_resolver registered [ 14.541901] NFS: Registering the id_resolver key type [ 14.544160] Key type id_resolver registered [ 14.545708] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started D-Bus System Message Bus. [ OK ] Started Daily Cleanup of Temporary Directories. Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg356-client login: [ 45.563373] hrtimer: interrupt took 3541337 ns [ 75.744948] libcfs: loading out-of-tree module taints kernel. [ 75.880641] Key type ._llcrypt registered [ 75.893806] Key type .llcrypt registered [ 77.549139] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 77.583796] alg: No test for adler32 (adler32-zlib) [ 79.345089] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 80.616682] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 82.543187] Key type lgssc registered [ 84.786762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 233.018333] Lustre: Mounted lustre-client [ 237.553842] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 256.326360] Lustre: DEBUG MARKER: oleg356-client.virtnet: executing check_logdir /tmp/testlogs/ [ 258.528155] Lustre: lustre-OST0000-osc-ffff9164051eb800: disconnect after 23s idle [ 260.880973] Lustre: DEBUG MARKER: oleg356-client.virtnet: executing yml_node [ 265.031407] Lustre: DEBUG MARKER: Client: 2.17.50.86 [ 267.664937] Lustre: DEBUG MARKER: MDS: 2.17.50.86 [ 269.975430] Lustre: DEBUG MARKER: OSS: 2.17.50.86 [ 271.719236] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri Jan 30 01:38:11 EST 2026 [ 291.654296] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 293.421838] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 295.217609] Lustre: DEBUG MARKER: === sanityn: start setup 01:38:34 (1769755114) === [ 296.258093] Lustre: Mounted lustre-client [ 300.810193] Lustre: DEBUG MARKER: oleg356-client.virtnet: executing check_config_client /mnt/lustre [ 322.116394] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 336.446346] Lustre: DEBUG MARKER: === sanityn: finish setup 01:39:16 (1769755156) === [ 339.162807] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 01:39:18 (1769755158) [ 348.431327] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 01:39:28 (1769755168) [ 355.553847] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 01:39:35 (1769755175) [ 363.007778] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 01:39:42 (1769755182) [ 370.763924] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 01:39:50 (1769755190) [ 377.386807] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 01:39:57 (1769755197) [ 384.050958] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 01:40:04 (1769755204) [ 391.570969] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 01:40:11 (1769755211) [ 393.518723] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 395.298822] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 01:40:15 (1769755215) [ 403.822984] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 01:40:23 (1769755223) [ 410.886918] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 01:40:30 (1769755230) [ 419.300178] Lustre: lustre-OST0000-osc-ffff9164051eb800: disconnect after 23s idle [ 419.318052] Lustre: Skipped 1 previous similar message [ 420.246953] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 01:40:39 (1769755239) [ 427.341387] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 01:40:47 (1769755247) [ 434.655385] Lustre: lustre-OST0001-osc-ffff9164051a6000: disconnect after 22s idle [ 435.070206] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 01:40:55 (1769755255) [ 442.132456] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 01:41:01 (1769755261) [ 448.809701] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 01:41:08 (1769755268) [ 455.136150] Lustre: lustre-OST0000-osc-ffff9164051eb800: disconnect after 22s idle [ 456.672097] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 01:41:16 (1769755276) [ 464.072366] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 01:41:24 (1769755284) [ 472.209437] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 01:41:31 (1769755291) [ 478.637966] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 01:41:38 (1769755298) [ 484.559340] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 01:41:44 (1769755304) [ 485.187285] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502302 file: /mnt/lustre/lockdir/lockfile=144115205272502300 [ 626.938636] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 01:44:07 (1769755447) [ 636.810982] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 01:44:16 (1769755456) [ 644.356081] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 01:44:24 (1769755464) [ 652.521498] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 01:44:32 (1769755472) [ 660.396496] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 01:44:40 (1769755480) [ 669.060478] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 01:44:49 (1769755489) [ 670.567682] Lustre: DEBUG MARKER: chmod [ 678.367642] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 01:44:58 (1769755498) [ 1589.723899] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 02:00:09 (1769756409) [ 1591.775351] Lustre: lustre-OST0000-osc-ffff9164051a6000: disconnect after 22s idle [ 1735.135438] Lustre: lustre-OST0001-osc-ffff9164051a6000: disconnect after 23s idle [ 1735.145834] Lustre: Skipped 1 previous similar message [ 1818.677821] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 02:03:58 (1769756638) [ 1932.992552] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 02:05:53 (1769756753) [ 2084.575269] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 02:08:24 (1769756904) [ 2124.256928] Lustre: lustre-OST0001-osc-ffff9164051a6000: disconnect after 20s idle [ 2126.836517] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 02:09:06 (1769756946) [ 2134.795532] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 02:09:14 (1769756954) [ 2135.769498] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2135.845239] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2135.933150] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.020627] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.117200] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.206980] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.282377] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.390118] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.461092] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.538566] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.587225] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.643652] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.732323] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.797426] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.857954] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.921902] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2136.980526] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2137.090282] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2137.175576] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2137.269123] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2137.359397] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2137.483979] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2137.589655] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2137.698671] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2137.851245] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2137.956594] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2138.065752] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2138.184649] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2138.342728] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2138.452767] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2138.566944] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2138.673228] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2138.784405] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2138.900763] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2138.997056] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.060090] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.126235] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.228693] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.305807] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.368513] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.423913] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.475942] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.566456] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.660571] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.755956] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.857312] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2139.969577] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2140.103880] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2140.150360] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2140.196555] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2140.242585] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2140.304571] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2140.428542] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2140.475520] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2140.529229] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2140.593226] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2140.734258] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2140.874394] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2141.045359] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2141.163837] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2141.291715] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2141.397849] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2141.545918] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2141.668707] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2141.783552] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2141.872506] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2141.973566] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.083328] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.202321] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.302356] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.423351] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.509735] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.577360] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.622656] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.671703] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.717407] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.797160] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.866633] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2142.958458] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2143.072908] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2143.156712] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2143.230215] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2143.286715] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2143.454170] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2143.586431] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2143.680383] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2143.729964] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2143.802366] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2143.883101] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2143.937240] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.004561] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.067227] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.152360] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.238114] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.312054] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.382973] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.471099] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.542145] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.605834] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.660779] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.718295] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.781789] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.846284] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2144.925363] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.024821] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.166713] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.229973] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.288142] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.372266] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.446764] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.503667] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.558706] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.631484] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.709480] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.809933] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.886466] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2145.933791] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2146.060581] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2146.150284] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2146.252722] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2146.379646] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2146.515900] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2146.617198] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2146.713706] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2146.838301] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2146.938934] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2147.006436] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2147.084797] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2147.167225] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2147.251252] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2147.309569] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2147.388850] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2147.515364] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2147.626717] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2147.721570] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2147.809900] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2147.878041] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2148.034200] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2148.141670] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2148.202110] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2148.319711] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2148.429525] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2148.564962] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2148.665414] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2148.841164] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2149.036353] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2149.192882] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2149.312984] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2149.438838] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2149.541051] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2149.708840] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2149.855312] Lustre: lustre-OST0000-osc-ffff9164051a6000: disconnect after 22s idle [ 2149.865550] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2149.942661] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2150.035129] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2150.131174] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2150.241808] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2150.339762] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2150.439697] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2150.569827] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2150.718406] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2150.876507] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2150.990408] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2151.080120] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2151.210426] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2151.337353] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2151.411742] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2151.480437] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2151.565639] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2151.626265] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2151.728340] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2151.800197] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2151.875288] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2151.949249] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.011450] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.100483] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.170827] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.249140] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.317669] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.394882] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.461190] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.525802] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.599086] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.693767] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.794995] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2152.905051] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.076948] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.177291] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.248944] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.350228] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.462213] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.528838] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.594845] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.675751] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.755338] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.830088] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.901600] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2153.972663] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.057354] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.156262] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.234647] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.296745] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.354023] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.431465] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.521262] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.587817] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.669579] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.740032] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.802730] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.881463] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2154.943586] rw_seq_cst_vs_d (30891): drop_caches: 3 [ 2160.108504] Lustre: lustre-OST0000-osc-ffff9164051eb800: disconnect after 22s idle [ 2162.844372] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 02:09:42 (1769756982) [ 2163.712186] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2163.789597] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2163.879784] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2163.993128] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2164.111430] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2164.180790] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2164.232772] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2164.293703] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2164.363107] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2164.496397] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2164.702341] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2164.941381] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2165.123239] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2165.326439] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2165.510109] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2165.664309] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2165.841513] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2165.943756] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2166.018159] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2166.068532] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2166.229937] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2166.339579] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2166.510658] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2166.629767] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2166.752120] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2166.859207] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2167.013883] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2167.320763] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2167.407573] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2167.453699] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2167.574524] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2167.694708] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2167.775809] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2167.918617] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2168.033448] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2168.139338] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2168.180468] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2168.292281] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2168.434493] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2168.483359] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2168.617535] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2168.739535] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2168.816599] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2168.914764] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2169.056347] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2169.217710] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2169.421216] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2169.564080] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2169.616200] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2169.691942] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2169.742799] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2169.788323] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2169.875369] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2169.935423] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2169.990961] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2170.144953] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2170.297378] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2170.340110] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2170.468217] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2170.603548] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2170.653559] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2170.789177] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2170.895080] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2170.983120] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2171.041233] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2171.102141] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2171.216789] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2171.270717] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2171.420640] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2171.539881] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2171.594492] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2171.830474] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2171.881232] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2172.113779] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2172.222347] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2172.375511] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2172.456146] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2172.608844] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2172.685338] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2172.742279] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2172.869805] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2173.026202] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2173.070163] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2173.277516] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2173.374059] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2173.484880] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2173.555931] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2173.676838] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2173.743041] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2173.859160] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2174.041941] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2174.141379] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2174.328332] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2174.367960] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2174.519922] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2174.565560] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2174.749280] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2174.852708] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2174.898940] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2175.063502] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2175.148242] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2175.241557] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2175.374695] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2175.425887] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2175.455238] Lustre: lustre-OST0001-osc-ffff9164051a6000: disconnect after 20s idle [ 2175.686197] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2175.765454] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2175.897151] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2176.026047] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2176.177698] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2176.311086] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2176.378331] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2176.440841] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2176.535809] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2176.557126] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2176.758821] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2176.898356] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2177.024066] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2177.092988] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2177.176824] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2177.218591] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2177.360493] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2177.772783] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2177.863978] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2177.983121] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2178.112854] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2178.325047] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2178.462063] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2178.522760] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2178.709170] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2178.766425] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2178.884588] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2179.076353] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2179.127104] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2179.227413] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2179.372441] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2179.631423] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2179.761355] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2179.877866] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2180.027874] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2180.083166] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2180.204313] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2180.281628] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2180.395807] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2180.459061] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2180.497823] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2180.626202] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2180.698184] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2180.868992] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2180.985732] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2181.182886] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2181.274199] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2181.323100] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2181.513645] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2181.678456] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2181.764640] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2181.931796] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2182.151918] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2182.286779] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2182.557753] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2182.638840] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2182.828929] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2182.883829] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2182.923598] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2182.960876] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2182.986711] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2183.010161] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2183.036632] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2183.053916] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2183.089175] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2183.116390] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2183.193426] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2183.260917] rw_seq_cst_vs_d (31473): drop_caches: 3 [ 2190.827881] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 02:10:10 (1769757010) [ 2198.259685] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 02:10:18 (1769757018) [ 2205.371484] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 02:10:25 (1769757025) [ 2237.160795] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 02:10:56 (1769757056) [ 2237.725754] bash (33920): drop_caches: 3 [ 2241.086080] bash (33920): drop_caches: 3 [ 2244.384699] bash (33920): drop_caches: 3 [ 2247.706974] bash (33920): drop_caches: 3 [ 2250.960560] bash (33920): drop_caches: 3 [ 2254.197480] bash (33920): drop_caches: 3 [ 2257.416457] bash (33920): drop_caches: 3 [ 2260.633362] bash (33920): drop_caches: 3 [ 2263.881959] bash (33920): drop_caches: 3 [ 2267.003438] bash (33920): drop_caches: 3 [ 2270.141744] bash (33920): drop_caches: 3 [ 2273.515095] bash (33920): drop_caches: 3 [ 2276.643229] bash (33920): drop_caches: 3 [ 2279.791718] bash (33920): drop_caches: 3 [ 2282.984022] bash (33920): drop_caches: 3 [ 2286.168657] bash (33920): drop_caches: 3 [ 2291.574653] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 02:11:50 (1769757110) [ 2302.177586] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 02:12:01 (1769757121) [ 2330.955173] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 02:12:31 (1769757151) [ 2340.673963] Lustre: DEBUG MARKER: loop 5 [ 2344.423645] Lustre: lustre-OST0000-osc-ffff9164051eb800: disconnect after 22s idle [ 2344.425351] Lustre: Skipped 1 previous similar message [ 2345.838863] Lustre: DEBUG MARKER: loop 10 [ 2350.639022] Lustre: DEBUG MARKER: loop 15 [ 2355.512951] Lustre: DEBUG MARKER: loop 20 [ 2362.838113] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 02:13:02 (1769757182) [ 2368.922694] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 02:13:09 (1769757189) [ 2375.123148] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 02:13:15 (1769757195) [ 2385.377590] Lustre: lustre-OST0000-osc-ffff9164051eb800: disconnect after 22s idle [ 2385.389140] Lustre: Skipped 1 previous similar message [ 2444.268541] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 02:14:24 (1769757264) [ 2450.677800] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 02:14:30 (1769757270) [ 2456.924684] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 02:14:37 (1769757277) [ 2464.291925] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 02:14:44 (1769757284) [ 2465.974366] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 2467.449696] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 02:14:47 (1769757287) [ 2472.428265] Lustre: lustre-OST0001-osc-ffff9164051eb800: disconnect after 21s idle [ 2472.436664] Lustre: Skipped 3 previous similar messages [ 2475.264406] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 02:14:55 (1769757295) [ 2483.207251] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 02:15:03 (1769757303) [ 2490.376634] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2491.695112] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 02:15:11 (1769757311) [ 2500.558322] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 02:15:20 (1769757320) [ 2500.854440] Lustre: *** cfs_fail_loc=314, val=0*** [ 2501.919493] Lustre: *** cfs_fail_loc=314, val=0*** [ 2501.921332] Lustre: Skipped 2 previous similar messages [ 2507.483572] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 02:15:27 (1769757327) [ 2516.765321] Lustre: *** cfs_fail_loc=314, val=0*** [ 2518.511387] Lustre: lustre-OST0000-osc-ffff9164051a6000: Connection to lustre-OST0000 (at 192.168.203.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2518.538190] LustreError: lustre-OST0000-osc-ffff9164051a6000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2518.561147] Lustre: lustre-OST0000-osc-ffff9164051a6000: Connection restored to 192.168.203.156@tcp (at 192.168.203.156@tcp) [ 2523.812839] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 02:15:43 (1769757343) [ 2524.071452] LustreError: 44957:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2527.095771] LustreError: 44957:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2532.217419] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 02:15:52 (1769757352) [ 2538.139814] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 02:15:58 (1769757358) [ 2544.363664] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2546.094717] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 02:16:06 (1769757366) [ 2548.126440] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2549.926301] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 02:16:09 (1769757369) [ 2551.397534] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 2553.319365] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 02:16:13 (1769757373) [ 2554.790925] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 2556.449996] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 02:16:16 (1769757376) [ 2557.981454] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 2560.053611] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 02:16:19 (1769757379) [ 2619.848115] Lustre: lustre-OST0001-osc-ffff9164051eb800: Connection to lustre-OST0001 (at 192.168.203.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2619.870536] LustreError: lustre-OST0001-osc-ffff9164051eb800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2619.902436] LustreError: lustre-OST0001-osc-ffff9164051a6000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2619.907770] Lustre: lustre-OST0001-osc-ffff9164051eb800: Connection restored to 192.168.203.156@tcp (at 192.168.203.156@tcp) [ 2619.946990] Lustre: Skipped 1 previous similar message [ 2635.212867] Lustre: lustre-OST0000-osc-ffff9164051eb800: Connection to lustre-OST0000 (at 192.168.203.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2635.233727] Lustre: Skipped 1 previous similar message [ 2635.252290] LustreError: lustre-OST0000-osc-ffff9164051eb800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2635.288761] Lustre: lustre-OST0000-osc-ffff9164051eb800: Connection restored to 192.168.203.156@tcp (at 192.168.203.156@tcp) [ 2641.375942] Lustre: lustre-OST0001-osc-ffff9164051eb800: disconnect after 21s idle [ 2641.382340] Lustre: Skipped 2 previous similar messages [ 2651.645610] Lustre: DEBUG MARKER: oleg356-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9164051a6000.ost_server_uuid 50 [ 2653.484850] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9164051a6000.ost_server_uuid in FULL state after 0 sec [ 2657.458308] Lustre: DEBUG MARKER: oleg356-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9164051a6000.ost_server_uuid 50 [ 2658.984233] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9164051a6000.ost_server_uuid in IDLE state after 0 sec [ 2663.646694] Lustre: DEBUG MARKER: oleg356-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9164051a6000.ost_server_uuid 50 [ 2665.476472] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9164051a6000.ost_server_uuid in FULL state after 0 sec [ 2669.915499] Lustre: DEBUG MARKER: oleg356-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9164051a6000.ost_server_uuid 50 [ 2671.333912] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9164051a6000.ost_server_uuid in IDLE state after 0 sec [ 2682.227147] Lustre: DEBUG MARKER: oleg356-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9164051a6000.ost_server_uuid 50 [ 2683.755504] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9164051a6000.ost_server_uuid in FULL state after 0 sec [ 2688.220728] Lustre: DEBUG MARKER: oleg356-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9164051a6000.ost_server_uuid 50 [ 2690.018797] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9164051a6000.ost_server_uuid in IDLE state after 0 sec [ 2691.485572] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 02:18:31 (1769757511) [ 2694.367827] Lustre: DEBUG MARKER: Race attempt 0 [ 2697.570268] Lustre: DEBUG MARKER: Wait for 51584 51621 for 60 sec... [ 2764.614871] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 02:19:44 (1769757584) [ 2772.675866] Lustre: DEBUG MARKER: start test - cycle (0) [ 2794.201858] Lustre: DEBUG MARKER: start test - cycle (1) [ 2817.950398] Lustre: DEBUG MARKER: start test - cycle (2) [ 2837.520412] Lustre: DEBUG MARKER: start test - cycle (3) [ 2858.530824] Lustre: DEBUG MARKER: start test - cycle (4) [ 2880.662453] Lustre: DEBUG MARKER: start test - cycle (5) [ 2904.339530] Lustre: DEBUG MARKER: start test - cycle (6) [ 2924.472929] Lustre: DEBUG MARKER: start test - cycle (7) [ 2945.585917] Lustre: DEBUG MARKER: start test - cycle (8) [ 2965.646041] Lustre: DEBUG MARKER: start test - cycle (9) [ 2986.419258] Lustre: DEBUG MARKER: start test - cycle (10) [ 3013.235339] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 02:23:53 (1769757833) [ 3015.135706] Lustre: lustre-OST0001-osc-ffff9164051a6000: disconnect after 21s idle [ 3015.146895] Lustre: Skipped 4 previous similar messages [ 3082.801620] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 02:25:03 (1769757903) [ 3089.124674] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 02:25:09 (1769757909) [ 3095.786472] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 02:25:16 (1769757916) [ 3103.336670] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 02:25:23 (1769757923) [ 3103.645706] Lustre: *** cfs_fail_loc=411, val=0*** [ 3109.333250] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 3110.816818] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 02:25:30 (1769757930) [ 3127.423373] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 02:25:47 (1769757947) [ 3142.338402] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 02:26:02 (1769757962) [ 3156.025788] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 02:26:16 (1769757976) [ 3169.196378] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 02:26:29 (1769757989) [ 3178.062536] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 02:26:38 (1769757998) [ 3187.584181] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 02:26:47 (1769758007) [ 3197.547534] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 02:26:57 (1769758017) [ 3207.259381] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 02:27:07 (1769758027) [ 3217.993484] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 02:27:18 (1769758038) [ 3228.802805] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 02:27:28 (1769758048) [ 3239.677079] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 02:27:40 (1769758060) [ 3249.719592] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 02:27:50 (1769758070) [ 3865.055364] Lustre: lustre-OST0000-osc-ffff9164051a6000: disconnect after 20s idle [ 3865.059360] Lustre: Skipped 9 previous similar messages [ 4126.623652] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 02:42:27 (1769758947) [ 4134.231400] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 02:42:34 (1769758954) [ 4141.347468] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 02:42:41 (1769758961) [ 4147.881740] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 02:42:48 (1769758968) [ 4154.620411] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 02:42:55 (1769758975) [ 4161.341603] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 02:43:02 (1769758982) [ 4168.114073] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 02:43:08 (1769758988) [ 4174.919169] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 02:43:15 (1769758995) [ 4182.287324] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 02:43:22 (1769759002) [ 4217.561763] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 02:43:58 (1769759038) [ 4224.527316] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 02:44:05 (1769759045) [ 4231.151789] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 02:44:11 (1769759051) [ 4237.555170] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 02:44:18 (1769759058) [ 4243.909589] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 02:44:24 (1769759064) [ 4250.216229] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 02:44:30 (1769759070) [ 4257.370139] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 02:44:37 (1769759077) [ 4263.893057] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 02:44:44 (1769759084) [ 4264.547808] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 4265.331715] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 02:44:45 (1769759085) [ 4317.210845] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 02:45:37 (1769759137) [ 4811.343894] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 02:53:52 (1769759632) [ 4816.778199] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 02:53:57 (1769759637) [ 4822.093347] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 02:54:02 (1769759642) [ 4827.301133] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 02:54:08 (1769759648) [ 4832.473116] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 02:54:13 (1769759653) [ 4837.813592] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 02:54:18 (1769759658) [ 4843.015982] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 02:54:23 (1769759663) [ 4848.379034] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 02:54:29 (1769759669) [ 4853.748517] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 02:54:34 (1769759674) [ 4854.224903] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 4854.810858] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 02:54:35 (1769759675) [ 4868.575120] Lustre: lustre-OST0000-osc-ffff9164051eb800: disconnect after 20s idle [ 4868.577560] Lustre: Skipped 6 previous similar messages [ 4883.676225] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 02:55:04 (1769759704) [ 4888.940837] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 02:55:09 (1769759709) [ 4894.567931] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 02:55:15 (1769759715) [ 4899.613022] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 02:55:20 (1769759720) [ 4904.710454] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 02:55:25 (1769759725) [ 4909.786786] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 02:55:30 (1769759730) [ 4914.933322] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 02:55:35 (1769759735) [ 4919.772868] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 02:55:40 (1769759740) [ 4920.275641] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 4920.888136] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 02:55:41 (1769759741) [ 5345.837804] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 03:02:46 (1769760166) [ 5351.096191] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 03:02:51 (1769760171) [ 5356.379477] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 03:02:57 (1769760177) [ 5361.802657] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 03:03:02 (1769760182) [ 5367.249353] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 03:03:08 (1769760188) [ 5372.695835] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 03:03:13 (1769760193) [ 5377.894993] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 03:03:18 (1769760198) [ 5383.025586] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 03:03:23 (1769760203) [ 5388.268220] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 03:03:29 (1769760209) [ 5388.772967] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 5389.345232] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 03:03:30 (1769760210) [ 5389.847495] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 5390.414569] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 03:03:31 (1769760211) [ 5390.892678] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 5391.428423] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 03:03:32 (1769760212) [ 5391.956937] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 5392.535105] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 03:03:33 (1769760213) [ 5393.068060] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 5393.629079] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 03:03:34 (1769760214) [ 5394.096148] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 5394.660579] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 03:03:35 (1769760215) [ 5395.112047] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 5395.656284] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 03:03:36 (1769760216) [ 5396.169299] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 5396.742808] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 03:03:37 (1769760217) [ 5396.822744] LustreError: 5437:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5398.903063] LustreError: 5437:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 5404.041046] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 03:03:44 (1769760224) [ 5408.405920] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 03:03:49 (1769760229) [ 5408.506212] LustreError: 220424:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5412.567138] LustreError: 220424:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5412.575565] LustreError: 220424:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5416.639136] LustreError: 220424:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5416.652355] LustreError: 220430:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5420.711379] LustreError: 220430:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5423.010927] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 03:04:03 (1769760243) [ 5429.774666] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 03:04:10 (1769760250) [ 5433.302920] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 03:04:13 (1769760253) [ 5437.605508] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 03:04:18 (1769760258) [ 5461.844871] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 03:04:42 (1769760282) [ 5469.469327] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 03:04:50 (1769760290) [ 5477.342747] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 03:04:58 (1769760298) [ 5490.101784] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 03:05:10 (1769760310) [ 5499.597341] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 03:05:20 (1769760320) [ 5500.127061] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 5500.686276] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 03:05:21 (1769760321) [ 5513.626923] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 03:05:34 (1769760334) [ 5531.955994] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 03:05:52 (1769760352) [ 5550.159273] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 03:06:10 (1769760370) [ 5598.615792] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 03:06:59 (1769760419) [ 5602.378541] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5606.432026] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 03:07:06 (1769760426) [ 5610.245362] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 03:07:10 (1769760430) [ 5613.739314] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 03:07:14 (1769760434) [ 5617.086243] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 03:07:17 (1769760437) [ 5641.256428] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 03:07:41 (1769760461) [ 5666.494287] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 03:08:07 (1769760487) [ 5669.850477] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 03:08:10 (1769760490) [ 5672.704870] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 03:08:13 (1769760493) [ 5675.822664] LustreError: lustre-MDT0000-mdc-ffff9164051a6000: operation ldlm_enqueue to node 192.168.203.156@tcp failed: rc = -35 [ 5679.655132] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 03:08:20 (1769760500) [ 5679.854714] LustreError: 2363:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5681.937019] LustreError: 2363:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 5687.712783] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 03:08:28 (1769760508) [ 5703.136454] Lustre: lustre-OST0000-osc-ffff9164051a6000: disconnect after 23s idle [ 5703.140574] Lustre: Skipped 3 previous similar messages [ 5723.921311] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 03:09:04 (1769760544) [ 5729.726655] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 03:09:09 (1769760549) [ 5736.864296] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 03:09:17 (1769760557) [ 5743.974560] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 03:09:24 (1769760564) [ 5751.035094] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 03:09:31 (1769760571) [ 5760.877281] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 03:09:41 (1769760581) [ 5769.124923] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 03:09:49 (1769760589) [ 5772.837001] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 03:09:53 (1769760593) [ 5776.923183] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 03:09:57 (1769760597) [ 5784.894898] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 03:10:05 (1769760605) [ 5826.711235] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 03:10:47 (1769760647) [ 5940.989856] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 03:12:41 (1769760761) [ 6051.393285] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 03:14:32 (1769760872) [ 6324.057052] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 03:19:04 (1769761144) [ 6352.002889] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 03:19:32 (1769761172) [ 6373.855221] Lustre: lustre-OST0001-osc-ffff9164051eb800: disconnect after 21s idle [ 6373.857774] Lustre: Skipped 15 previous similar messages [ 6380.622737] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 03:20:01 (1769761201) [ 6401.728161] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 03:20:22 (1769761222) [ 6459.191802] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 03:21:20 (1769761280) [ 6511.965826] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 03:22:12 (1769761332) [ 6600.932511] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 03:23:41 (1769761421) [ 6601.895955] LustreError: 265142:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9164051eb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6601.900787] LustreError: 265142:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6601.912096] Lustre: Unmounted lustre-client [ 6602.833763] LustreError: 265155:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9164051a6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6602.837397] LustreError: 265155:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6602.840893] LustreError: 265155:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6602.843208] LustreError: 265155:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 6602.856776] Lustre: Unmounted lustre-client [ 6646.464688] Lustre: Mounted lustre-client [ 6647.986533] Lustre: Mounted lustre-client [ 6649.013964] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6707.920179] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 03:25:28 (1769761528) [ 6715.551731] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 03:25:36 (1769761536) [ 6718.713866] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 03:25:39 (1769761539) [ 6769.568862] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 03:26:30 (1769761590) [ 6810.872475] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 03:27:11 (1769761631) [ 6814.416693] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 03:27:15 (1769761635) [ 6850.112292] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 03:27:50 (1769761670) [ 6861.100737] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 03:28:01 (1769761681) [ 6902.975764] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 03:28:43 (1769761723) [ 6905.985418] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 03:28:46 (1769761726) [ 6918.688763] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 03:28:59 (1769761739) [ 6919.188012] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 6919.732762] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 03:29:00 (1769761740) [ 6920.200367] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 6920.721614] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 03:29:01 (1769761741) [ 6921.204585] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 6921.755962] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 03:29:02 (1769761742) [ 6922.261485] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 6922.774346] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 03:29:03 (1769761743) [ 6923.283487] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6923.815256] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 03:29:04 (1769761744) [ 6946.495491] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 03:29:27 (1769761767) [ 6948.520931] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 03:29:29 (1769761769) [ 6949.018157] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 6949.552675] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 03:29:30 (1769761770) [ 6956.959361] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 03:29:37 (1769761777) [ 6959.585499] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 03:29:40 (1769761780) [ 6960.052749] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 6960.571772] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 03:29:41 (1769761781) [ 6961.023463] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 6961.561102] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 03:29:42 (1769761782) [ 6962.004176] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 6962.512145] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 03:29:43 (1769761783) [ 6971.119097] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 03:29:51 (1769761791) [ 6971.169193] Lustre: DEBUG MARKER: write [ 6971.186846] LustreError: 266577:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6973.193119] Lustre: DEBUG MARKER: kill 295311 [ 6973.195906] LustreError: 295311:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6976.287135] LustreError: 266577:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 6979.231125] LustreError: 295311:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 6981.192626] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 03:30:02 (1769761802) [ 6983.344983] LustreError: 295916:0:(rw.c:1959:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 6993.439114] LustreError: 295916:0:(rw.c:1959:ll_readpage()) cfs_fail_timeout id 1422 awake [ 6995.549783] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 03:30:16 (1769761816) [ 6995.640984] LustreError: 296496:0:(rw.c:2204:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6997.727112] LustreError: 296496:0:(rw.c:2204:ll_readpage()) cfs_fail_timeout interrupted [ 7003.755815] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 03:30:24 (1769761824) [ 7004.281935] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 7004.849348] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 03:30:25 (1769761825) [ 7006.733261] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 03:30:27 (1769761827) [ 7008.646160] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 03:30:29 (1769761829) [ 7010.590427] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 03:30:31 (1769761831) [ 7012.539723] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 03:30:33 (1769761833) [ 7014.415354] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 03:30:35 (1769761835) [ 7016.604469] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 03:30:37 (1769761837) [ 7019.621539] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 03:30:40 (1769761840) [ 7021.535598] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 03:30:42 (1769761842) [ 7022.112439] Lustre: *** cfs_fail_loc=415, val=0*** [ 7028.446980] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 03:30:49 (1769761849) [ 7047.201715] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 03:31:08 (1769761868) [ 7047.267803] LustreError: 271302:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7047.270087] LustreError: 271302:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7052.367155] LustreError: 271302:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7052.370249] LustreError: 271302:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7062.559099] LustreError: 266576:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7062.561399] LustreError: 266576:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 4 previous similar messages [ 7064.507814] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 03:31:25 (1769761885) [ 7066.588463] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 03:31:27 (1769761887) [ 7068.672417] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 03:31:29 (1769761889) [ 7070.610889] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 03:31:31 (1769761891) [ 7074.598307] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 03:31:35 (1769761895) [ 7082.711347] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 03:31:43 (1769761903) [ 7082.843276] LustreError: 306942:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 7082.845137] LustreError: 306942:0:(osc_request.c:2944:osc_build_rpc()) Skipped 6 previous similar messages [ 7086.903093] LustreError: 306942:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 7086.905583] LustreError: 306942:0:(osc_request.c:2944:osc_build_rpc()) Skipped 1 previous similar message [ 7089.090778] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 03:31:49 (1769761909) [ 7089.843607] LustreError: 307639:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9164370a6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7089.847415] LustreError: 307639:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7089.851229] LustreError: 307639:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7089.853563] LustreError: 307639:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7089.866255] Lustre: Unmounted lustre-client [ 7090.694918] Lustre: DEBUG MARKER: Iteration 1 [ 7090.804263] LustreError: 307820:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7090.804292] LustreError: 307821:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7090.808860] LustreError: 307820:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7090.852561] Lustre: Mounted lustre-client [ 7092.164993] Key type lgssc unregistered [ 7092.269376] LNet: 308169:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7092.271317] LNetError: 308169:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7092.280804] LNet: Removed LNI 192.168.203.56@tcp [ 7092.545111] Key type .llcrypt unregistered [ 7092.547302] Key type ._llcrypt unregistered [ 7092.808755] Key type ._llcrypt registered [ 7092.809562] Key type .llcrypt registered [ 7093.029440] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7093.033556] alg: No test for adler32 (adler32-zlib) [ 7093.987106] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7094.238228] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7095.847130] Key type lgssc registered [ 7096.340622] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7100.211969] Lustre: DEBUG MARKER: Iteration 2 [ 7100.303522] LustreError: 308986:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7100.303574] LustreError: 308988:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7100.307929] LustreError: 308986:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7101.358342] Lustre: Mounted lustre-client [ 7101.359388] Lustre: Skipped 1 previous similar message [ 7101.790356] LustreError: 309089:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916408ad2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7101.792433] LustreError: 309090:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7101.795700] LustreError: 309089:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7101.811081] Lustre: Unmounted lustre-client [ 7102.711532] Key type lgssc unregistered [ 7102.830466] LNet: 309332:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7102.832553] LNetError: 309332:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7102.841769] LNet: Removed LNI 192.168.203.56@tcp [ 7103.050083] Key type .llcrypt unregistered [ 7103.051036] Key type ._llcrypt unregistered [ 7103.287859] Key type ._llcrypt registered [ 7103.289598] Key type .llcrypt registered [ 7103.481613] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7103.487266] alg: No test for adler32 (adler32-zlib) [ 7104.338176] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7104.417604] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7105.999126] Key type lgssc registered [ 7106.355651] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7109.690141] Lustre: DEBUG MARKER: Iteration 3 [ 7109.787594] LustreError: 310150:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7109.788932] LustreError: 310151:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7109.792629] LustreError: 310150:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7110.839768] Lustre: Mounted lustre-client [ 7111.260568] LustreError: 310256:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916420601800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7111.263638] LustreError: 310256:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7111.266446] LustreError: 310257:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7111.268035] LustreError: 310257:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7111.281423] Lustre: Unmounted lustre-client [ 7112.184463] Key type lgssc unregistered [ 7112.294440] LNet: 310498:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7112.296361] LNetError: 310498:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7112.304564] LNet: Removed LNI 192.168.203.56@tcp [ 7112.517079] Key type .llcrypt unregistered [ 7112.518044] Key type ._llcrypt unregistered [ 7112.786727] Key type ._llcrypt registered [ 7112.787982] Key type .llcrypt registered [ 7112.950634] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7112.955306] alg: No test for adler32 (adler32-zlib) [ 7113.801087] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7113.881099] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7115.455125] Key type lgssc registered [ 7115.787501] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7118.875050] Lustre: DEBUG MARKER: Iteration 4 [ 7118.963569] LustreError: 311315:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7118.963593] LustreError: 311316:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7118.967325] LustreError: 311315:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7120.008242] Lustre: Mounted lustre-client [ 7120.009858] Lustre: Skipped 1 previous similar message [ 7120.408469] LustreError: 311424:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916404d7b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7120.410664] LustreError: 311423:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7120.411829] LustreError: 311424:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7120.415316] LustreError: 311423:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7120.442076] Lustre: Unmounted lustre-client [ 7121.292631] Key type lgssc unregistered [ 7121.397425] LNet: 311665:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7121.400280] LNetError: 311665:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7121.407586] LNet: Removed LNI 192.168.203.56@tcp [ 7121.620092] Key type .llcrypt unregistered [ 7121.621059] Key type ._llcrypt unregistered [ 7121.866276] Key type ._llcrypt registered [ 7121.867189] Key type .llcrypt registered [ 7122.047453] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7122.051856] alg: No test for adler32 (adler32-zlib) [ 7122.905338] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7122.983615] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7124.567158] Key type lgssc registered [ 7124.916805] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7128.205202] Lustre: DEBUG MARKER: Iteration 5 [ 7128.307730] LustreError: 312482:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7128.307776] LustreError: 312483:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7128.311858] LustreError: 312482:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7129.359249] Lustre: Mounted lustre-client [ 7129.360852] Lustre: Skipped 1 previous similar message [ 7129.765876] LustreError: 312590:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916405177000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7129.770271] LustreError: 312590:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7129.772230] LustreError: 312590:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7129.792521] Lustre: Unmounted lustre-client [ 7130.659562] Key type lgssc unregistered [ 7130.765458] LNet: 312832:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7130.767391] LNetError: 312832:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7130.774619] LNet: Removed LNI 192.168.203.56@tcp [ 7130.987119] Key type .llcrypt unregistered [ 7130.988044] Key type ._llcrypt unregistered [ 7131.180207] Key type ._llcrypt registered [ 7131.182320] Key type .llcrypt registered [ 7131.387513] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7131.392666] alg: No test for adler32 (adler32-zlib) [ 7132.240512] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7132.311602] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7133.887138] Key type lgssc registered [ 7134.248450] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7137.547522] Lustre: DEBUG MARKER: Iteration 6 [ 7137.638635] LustreError: 313649:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7137.641128] LustreError: 313650:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7137.644168] LustreError: 313649:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7138.688354] Lustre: Mounted lustre-client [ 7138.689892] Lustre: Skipped 1 previous similar message [ 7139.099316] LustreError: 313751:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916410c22000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7139.100713] LustreError: 313752:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7139.102162] LustreError: 313751:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7139.122064] Lustre: Unmounted lustre-client [ 7139.985635] Key type lgssc unregistered [ 7140.097443] LNet: 313992:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7140.099496] LNetError: 313992:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7140.109605] LNet: Removed LNI 192.168.203.56@tcp [ 7140.330090] Key type .llcrypt unregistered [ 7140.331068] Key type ._llcrypt unregistered [ 7140.562456] Key type ._llcrypt registered [ 7140.563439] Key type .llcrypt registered [ 7140.724170] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7140.729589] alg: No test for adler32 (adler32-zlib) [ 7141.569900] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7141.639946] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7143.223140] Key type lgssc registered [ 7143.597314] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7147.217366] Lustre: DEBUG MARKER: Iteration 7 [ 7147.336111] LustreError: 314809:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7147.336139] LustreError: 314810:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7147.342058] LustreError: 314809:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7148.398864] Lustre: Mounted lustre-client [ 7148.400371] Lustre: Skipped 1 previous similar message [ 7148.951833] LustreError: 314916:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916403141800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7148.955537] LustreError: 314916:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7148.958103] LustreError: 314915:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7148.994112] Lustre: Unmounted lustre-client [ 7148.995355] Lustre: Skipped 1 previous similar message [ 7149.990894] Key type lgssc unregistered [ 7150.103498] LNet: 315158:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7150.106336] LNetError: 315158:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7150.114873] LNet: Removed LNI 192.168.203.56@tcp [ 7150.358123] Key type .llcrypt unregistered [ 7150.359176] Key type ._llcrypt unregistered [ 7150.638184] Key type ._llcrypt registered [ 7150.642443] Key type .llcrypt registered [ 7150.848701] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7150.854093] alg: No test for adler32 (adler32-zlib) [ 7151.717930] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7151.798086] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7153.383111] Key type lgssc registered [ 7153.730607] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7156.963827] Lustre: DEBUG MARKER: Iteration 8 [ 7157.059907] LustreError: 315977:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7157.059944] LustreError: 315976:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7157.064816] LustreError: 315977:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7158.106945] Lustre: Mounted lustre-client [ 7158.517194] LustreError: 316082:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916438e5a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7158.520879] LustreError: 316082:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7158.520954] LustreError: 316083:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 7158.524659] LustreError: 316083:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7158.541081] Lustre: Unmounted lustre-client [ 7159.439263] Key type lgssc unregistered [ 7159.556377] LNet: 316324:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7159.559119] LNetError: 316324:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7159.565672] LNet: Removed LNI 192.168.203.56@tcp [ 7159.789118] Key type .llcrypt unregistered [ 7159.790648] Key type ._llcrypt unregistered [ 7160.009397] Key type ._llcrypt registered [ 7160.010300] Key type .llcrypt registered [ 7160.193142] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7160.197821] alg: No test for adler32 (adler32-zlib) [ 7161.036822] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7161.106562] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7162.687098] Key type lgssc registered [ 7163.031144] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7166.404311] Lustre: DEBUG MARKER: Iteration 9 [ 7166.488798] LustreError: 317142:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7166.488825] LustreError: 317143:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7166.493194] LustreError: 317142:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7167.535241] Lustre: Mounted lustre-client [ 7167.537034] Lustre: Skipped 1 previous similar message [ 7167.928477] LustreError: 317250:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916408f43800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7167.931100] LustreError: 317249:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7167.932408] LustreError: 317250:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7167.951087] Lustre: Unmounted lustre-client [ 7168.861602] Key type lgssc unregistered [ 7168.969511] LNet: 317491:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7168.971952] LNetError: 317491:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7168.980943] LNet: Removed LNI 192.168.203.56@tcp [ 7169.189100] Key type .llcrypt unregistered [ 7169.190608] Key type ._llcrypt unregistered [ 7169.498788] Key type ._llcrypt registered [ 7169.502161] Key type .llcrypt registered [ 7169.620330] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7169.625661] alg: No test for adler32 (adler32-zlib) [ 7170.465590] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7170.546844] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7172.127195] Key type lgssc registered [ 7172.496028] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7175.649218] Lustre: DEBUG MARKER: Iteration 10 [ 7175.746470] LustreError: 318308:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7175.746770] LustreError: 318309:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7175.751900] LustreError: 318308:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7176.802060] Lustre: Mounted lustre-client [ 7176.803986] Lustre: Skipped 1 previous similar message [ 7177.200133] LustreError: 318415:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916419b16800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7177.201781] LustreError: 318416:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7177.203821] LustreError: 318415:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7177.223098] Lustre: Unmounted lustre-client [ 7178.072028] Key type lgssc unregistered [ 7178.179404] LNet: 318657:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7178.182049] LNetError: 318657:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7178.189648] LNet: Removed LNI 192.168.203.56@tcp [ 7178.404093] Key type .llcrypt unregistered [ 7178.405312] Key type ._llcrypt unregistered [ 7178.602511] Key type ._llcrypt registered [ 7178.603701] Key type .llcrypt registered [ 7178.784865] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7178.798269] alg: No test for adler32 (adler32-zlib) [ 7179.646913] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7179.722719] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7181.303148] Key type lgssc registered [ 7181.675930] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7184.974114] Lustre: DEBUG MARKER: Iteration 11 [ 7185.061848] LustreError: 319473:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7185.064143] LustreError: 319474:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7185.066091] LustreError: 319473:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7186.105891] Lustre: Mounted lustre-client [ 7186.107689] Lustre: Skipped 1 previous similar message [ 7186.534390] LustreError: 319580:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916410867800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7186.539108] LustreError: 319580:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7186.539168] LustreError: 319581:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7186.582390] Lustre: Unmounted lustre-client [ 7187.443094] Key type lgssc unregistered [ 7187.557427] LNet: 319821:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7187.559490] LNetError: 319821:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7187.569647] LNet: Removed LNI 192.168.203.56@tcp [ 7187.785103] Key type .llcrypt unregistered [ 7187.786539] Key type ._llcrypt unregistered [ 7188.045980] Key type ._llcrypt registered [ 7188.047266] Key type .llcrypt registered [ 7188.224248] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7188.229823] alg: No test for adler32 (adler32-zlib) [ 7189.091065] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7189.173927] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7190.759126] Key type lgssc registered [ 7191.115072] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7194.272325] Lustre: DEBUG MARKER: Iteration 12 [ 7194.365644] LustreError: 320639:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7194.365686] LustreError: 320640:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7194.369863] LustreError: 320639:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7195.409083] Lustre: Mounted lustre-client [ 7195.834355] LustreError: 320746:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916405b06000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7195.838316] LustreError: 320747:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7195.838935] LustreError: 320746:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7195.856955] Lustre: Unmounted lustre-client [ 7196.784837] Key type lgssc unregistered [ 7196.909435] LNet: 320988:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7196.911376] LNetError: 320988:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7196.917651] LNet: Removed LNI 192.168.203.56@tcp [ 7197.158126] Key type .llcrypt unregistered [ 7197.159359] Key type ._llcrypt unregistered [ 7197.455983] Key type ._llcrypt registered [ 7197.456933] Key type .llcrypt registered [ 7197.685855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7197.690922] alg: No test for adler32 (adler32-zlib) [ 7198.549436] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7198.633753] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7200.223207] Key type lgssc registered [ 7200.603464] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7203.925185] Lustre: DEBUG MARKER: Iteration 13 [ 7204.014552] LustreError: 321806:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7204.014623] LustreError: 321807:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7204.020224] LustreError: 321806:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7205.066653] Lustre: Mounted lustre-client [ 7205.068818] Lustre: Skipped 1 previous similar message [ 7205.486438] LustreError: 321915:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916418cb1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7205.488981] LustreError: 321914:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7205.490906] LustreError: 321915:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7205.508111] Lustre: Unmounted lustre-client [ 7206.431540] Key type lgssc unregistered [ 7206.526500] LNet: 322156:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7206.528582] LNetError: 322156:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7206.534676] LNet: Removed LNI 192.168.203.56@tcp [ 7206.753093] Key type .llcrypt unregistered [ 7206.753984] Key type ._llcrypt unregistered [ 7206.981973] Key type ._llcrypt registered [ 7206.983474] Key type .llcrypt registered [ 7207.166305] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7207.171908] alg: No test for adler32 (adler32-zlib) [ 7208.012790] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7208.092237] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7209.671087] Key type lgssc registered [ 7210.039069] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7213.464697] Lustre: DEBUG MARKER: Iteration 14 [ 7213.559658] LustreError: 322973:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7213.559692] LustreError: 322974:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7213.563734] LustreError: 322973:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7214.609123] Lustre: Mounted lustre-client [ 7214.610814] Lustre: Skipped 1 previous similar message [ 7215.034948] LustreError: 323080:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916420601800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7215.037401] LustreError: 323079:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7215.038888] LustreError: 323080:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7215.063393] Lustre: Unmounted lustre-client [ 7215.890896] Key type lgssc unregistered [ 7216.008412] LNet: 323320:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7216.010701] LNetError: 323320:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7216.016621] LNet: Removed LNI 192.168.203.56@tcp [ 7216.242117] Key type .llcrypt unregistered [ 7216.243607] Key type ._llcrypt unregistered [ 7216.450518] Key type ._llcrypt registered [ 7216.452656] Key type .llcrypt registered [ 7216.644616] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7216.649288] alg: No test for adler32 (adler32-zlib) [ 7217.491847] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7217.570246] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7219.151155] Key type lgssc registered [ 7219.480958] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7222.547779] Lustre: DEBUG MARKER: Iteration 15 [ 7222.632334] LustreError: 324138:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7222.632363] LustreError: 324139:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7222.636166] LustreError: 324138:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7223.676111] Lustre: Mounted lustre-client [ 7223.677447] Lustre: Skipped 1 previous similar message [ 7224.073433] LustreError: 324246:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916410c26000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7224.077340] LustreError: 324247:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7224.077390] LustreError: 324246:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7224.096538] Lustre: Unmounted lustre-client [ 7224.958980] Key type lgssc unregistered [ 7225.063322] LNet: 324488:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7225.065916] LNetError: 324488:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7225.074662] LNet: Removed LNI 192.168.203.56@tcp [ 7225.269099] Key type .llcrypt unregistered [ 7225.270435] Key type ._llcrypt unregistered [ 7225.517440] Key type ._llcrypt registered [ 7225.519633] Key type .llcrypt registered [ 7225.689216] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7225.693911] alg: No test for adler32 (adler32-zlib) [ 7226.542583] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7226.634771] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7228.215147] Key type lgssc registered [ 7228.591747] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7231.706326] Lustre: DEBUG MARKER: Iteration 16 [ 7231.810288] LustreError: 325305:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7231.810343] LustreError: 325306:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7231.816484] LustreError: 325305:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7232.867143] Lustre: Mounted lustre-client [ 7232.868565] Lustre: Skipped 1 previous similar message [ 7233.280590] LustreError: 325412:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916405b1a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7233.282855] LustreError: 325413:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7233.283391] LustreError: 325412:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7233.288611] LustreError: 325413:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7233.298076] Lustre: Unmounted lustre-client [ 7234.233904] Key type lgssc unregistered [ 7234.335438] LNet: 325655:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7234.338286] LNetError: 325655:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7234.346661] LNet: Removed LNI 192.168.203.56@tcp [ 7234.553079] Key type .llcrypt unregistered [ 7234.554091] Key type ._llcrypt unregistered [ 7234.805682] Key type ._llcrypt registered [ 7234.806737] Key type .llcrypt registered [ 7234.978229] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7234.983058] alg: No test for adler32 (adler32-zlib) [ 7235.827375] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7235.900882] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7237.479136] Key type lgssc registered [ 7237.844406] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7241.107956] Lustre: DEBUG MARKER: Iteration 17 [ 7241.201433] LustreError: 326472:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7241.201758] LustreError: 326473:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7241.205077] LustreError: 326472:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7242.252456] Lustre: Mounted lustre-client [ 7242.254085] Lustre: Skipped 1 previous similar message [ 7242.662636] LustreError: 326580:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916421379800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7242.666671] LustreError: 326580:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7242.667087] LustreError: 326581:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7242.671733] LustreError: 326581:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7242.686076] Lustre: Unmounted lustre-client [ 7242.688059] Lustre: Skipped 1 previous similar message [ 7243.529142] Key type lgssc unregistered [ 7243.652480] LNet: 326821:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7243.654875] LNetError: 326821:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7243.664727] LNet: Removed LNI 192.168.203.56@tcp [ 7243.873116] Key type .llcrypt unregistered [ 7243.874655] Key type ._llcrypt unregistered [ 7244.121548] Key type ._llcrypt registered [ 7244.122774] Key type .llcrypt registered [ 7244.301227] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7244.305895] alg: No test for adler32 (adler32-zlib) [ 7245.150329] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7245.227408] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7246.807140] Key type lgssc registered [ 7247.161804] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7250.376709] Lustre: DEBUG MARKER: Iteration 18 [ 7250.468195] LustreError: 327640:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7250.468251] LustreError: 327641:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7250.472949] LustreError: 327640:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7251.521446] Lustre: Mounted lustre-client [ 7251.522524] Lustre: Skipped 1 previous similar message [ 7251.917535] LustreError: 327742:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91643bea2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7251.920113] LustreError: 327743:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7251.921277] LustreError: 327742:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7251.936347] Lustre: Unmounted lustre-client [ 7252.735669] Key type lgssc unregistered [ 7252.839300] LNet: 327985:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7252.841817] LNetError: 327985:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7252.850574] LNet: Removed LNI 192.168.203.56@tcp [ 7253.048108] Key type .llcrypt unregistered [ 7253.049108] Key type ._llcrypt unregistered [ 7253.299194] Key type ._llcrypt registered [ 7253.300278] Key type .llcrypt registered [ 7253.455649] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7253.459825] alg: No test for adler32 (adler32-zlib) [ 7254.307945] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7254.394871] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7255.975114] Key type lgssc registered [ 7256.324564] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7259.654767] Lustre: DEBUG MARKER: Iteration 19 [ 7259.775365] LustreError: 328802:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7259.781719] LustreError: 328803:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7259.783643] LustreError: 328802:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 7260.844956] Lustre: Mounted lustre-client [ 7260.845983] Lustre: Skipped 1 previous similar message [ 7261.310581] LustreError: 328912:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916403b3c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7261.313854] LustreError: 328911:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7261.316194] LustreError: 328912:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7261.335138] Lustre: Unmounted lustre-client [ 7262.347422] Key type lgssc unregistered [ 7262.467444] LNet: 329154:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7262.469693] LNetError: 329154:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7262.477726] LNet: Removed LNI 192.168.203.56@tcp [ 7262.690155] Key type .llcrypt unregistered [ 7262.691931] Key type ._llcrypt unregistered [ 7262.953121] Key type ._llcrypt registered [ 7262.954268] Key type .llcrypt registered [ 7263.242600] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7263.247868] alg: No test for adler32 (adler32-zlib) [ 7264.099304] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7264.183651] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7265.775170] Key type lgssc registered [ 7266.202418] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7269.792318] Lustre: DEBUG MARKER: Iteration 20 [ 7269.892476] LustreError: 329971:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7269.892510] LustreError: 329972:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7269.898684] LustreError: 329971:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7270.943029] Lustre: Mounted lustre-client [ 7270.944410] Lustre: Skipped 1 previous similar message [ 7271.405646] LustreError: 330074:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916410c22800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7271.408603] LustreError: 330075:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7271.410629] LustreError: 330074:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7271.432954] Lustre: Unmounted lustre-client [ 7272.405748] Key type lgssc unregistered [ 7272.520571] LNet: 330316:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7272.524468] LNetError: 330316:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7272.536144] LNet: Removed LNI 192.168.203.56@tcp [ 7272.766206] Key type .llcrypt unregistered [ 7272.767224] Key type ._llcrypt unregistered [ 7273.028344] Key type ._llcrypt registered [ 7273.032907] Key type .llcrypt registered [ 7273.245220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7273.252115] alg: No test for adler32 (adler32-zlib) [ 7274.103673] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7274.184815] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7275.775144] Key type lgssc registered [ 7276.172598] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7279.621289] Lustre: DEBUG MARKER: Iteration 21 [ 7279.748252] LustreError: 331134:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7279.748302] LustreError: 331135:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7279.755103] LustreError: 331134:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7280.795579] Lustre: Mounted lustre-client [ 7280.798276] Lustre: Skipped 1 previous similar message [ 7281.250436] LustreError: 331241:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9164102cf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7281.253726] LustreError: 331242:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7281.254738] LustreError: 331241:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7281.271167] Lustre: Unmounted lustre-client [ 7282.220297] Key type lgssc unregistered [ 7282.336664] LNet: 331483:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7282.340076] LNetError: 331483:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7282.352677] LNet: Removed LNI 192.168.203.56@tcp [ 7282.609149] Key type .llcrypt unregistered [ 7282.610392] Key type ._llcrypt unregistered [ 7282.891034] Key type ._llcrypt registered [ 7282.892297] Key type .llcrypt registered [ 7283.093960] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7283.100849] alg: No test for adler32 (adler32-zlib) [ 7283.941962] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7284.018479] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7285.591131] Key type lgssc registered [ 7285.938208] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7289.105366] Lustre: DEBUG MARKER: Iteration 22 [ 7289.197227] LustreError: 332301:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7289.197254] LustreError: 332302:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7289.202094] LustreError: 332301:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7290.244596] Lustre: Mounted lustre-client [ 7290.246048] Lustre: Skipped 1 previous similar message [ 7290.672593] LustreError: 332409:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916404d7e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7290.676040] LustreError: 332409:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7290.678543] LustreError: 332408:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7290.680557] LustreError: 332408:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7290.697076] Lustre: Unmounted lustre-client [ 7291.587340] Key type lgssc unregistered [ 7291.707499] LNet: 332650:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7291.709820] LNetError: 332650:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7291.717755] LNet: Removed LNI 192.168.203.56@tcp [ 7291.924127] Key type .llcrypt unregistered [ 7291.925361] Key type ._llcrypt unregistered [ 7292.166712] Key type ._llcrypt registered [ 7292.167573] Key type .llcrypt registered [ 7292.335749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7292.340741] alg: No test for adler32 (adler32-zlib) [ 7293.181506] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7293.250045] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7294.823176] Key type lgssc registered [ 7295.163816] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7298.270508] Lustre: DEBUG MARKER: Iteration 23 [ 7298.359082] LustreError: 333469:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7298.359125] LustreError: 333470:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7298.363268] LustreError: 333469:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7299.410142] Lustre: Mounted lustre-client [ 7299.812303] LustreError: 333573:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916403145800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7299.815292] LustreError: 333573:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7299.817877] LustreError: 333572:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7299.820761] LustreError: 333572:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7299.829063] Lustre: Unmounted lustre-client [ 7300.687191] Key type lgssc unregistered [ 7300.795523] LNet: 333814:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7300.797957] LNetError: 333814:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7300.806611] LNet: Removed LNI 192.168.203.56@tcp [ 7301.017113] Key type .llcrypt unregistered [ 7301.018145] Key type ._llcrypt unregistered [ 7301.241412] Key type ._llcrypt registered [ 7301.242924] Key type .llcrypt registered [ 7301.431069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7301.437133] alg: No test for adler32 (adler32-zlib) [ 7302.292211] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7302.367217] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7303.943153] Key type lgssc registered [ 7304.290817] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7307.396758] Lustre: DEBUG MARKER: Iteration 24 [ 7307.485143] LustreError: 334631:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7307.485567] LustreError: 334632:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7307.490381] LustreError: 334631:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7308.531641] Lustre: Mounted lustre-client [ 7308.532892] Lustre: Skipped 1 previous similar message [ 7308.933678] LustreError: 334733:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916437445800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7308.938437] LustreError: 334733:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 7308.940340] LustreError: 334733:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7308.950055] Lustre: Unmounted lustre-client [ 7309.807300] Key type lgssc unregistered [ 7309.915397] LNet: 334975:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7309.917895] LNetError: 334975:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7309.925627] LNet: Removed LNI 192.168.203.56@tcp [ 7310.128126] Key type .llcrypt unregistered [ 7310.128968] Key type ._llcrypt unregistered [ 7310.340326] Key type ._llcrypt registered [ 7310.341867] Key type .llcrypt registered [ 7310.526488] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7310.532747] alg: No test for adler32 (adler32-zlib) [ 7311.372609] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7311.446383] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7313.023103] Key type lgssc registered [ 7313.363979] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7316.506539] Lustre: DEBUG MARKER: Iteration 25 [ 7316.598882] LustreError: 335792:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7316.598923] LustreError: 335793:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7316.603629] LustreError: 335792:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7317.648441] Lustre: Mounted lustre-client [ 7317.649758] Lustre: Skipped 1 previous similar message [ 7318.078401] LustreError: 335901:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916408f45800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7318.082144] LustreError: 335900:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7318.083257] LustreError: 335901:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7318.096452] Lustre: Unmounted lustre-client [ 7318.986918] Key type lgssc unregistered [ 7319.094446] LNet: 336142:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7319.096325] LNetError: 336142:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7319.104531] LNet: Removed LNI 192.168.203.56@tcp [ 7319.335080] Key type .llcrypt unregistered [ 7319.336466] Key type ._llcrypt unregistered [ 7319.583630] Key type ._llcrypt registered [ 7319.588367] Key type .llcrypt registered [ 7319.751687] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7319.756826] alg: No test for adler32 (adler32-zlib) [ 7320.609888] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7320.687136] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7322.271128] Key type lgssc registered [ 7322.596832] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7325.990081] Lustre: DEBUG MARKER: Iteration 26 [ 7326.086547] LustreError: 336960:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7326.086593] LustreError: 336961:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7326.091409] LustreError: 336960:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7327.141916] Lustre: Mounted lustre-client [ 7327.143489] Lustre: Skipped 1 previous similar message [ 7327.577856] LustreError: 337067:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9164102cc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7327.580796] LustreError: 337067:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7327.585061] LustreError: 337068:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7327.587645] LustreError: 337068:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7327.605529] Lustre: Unmounted lustre-client [ 7328.527454] Key type lgssc unregistered [ 7328.644395] LNet: 337310:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7328.646274] LNetError: 337310:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7328.652747] LNet: Removed LNI 192.168.203.56@tcp [ 7328.874100] Key type .llcrypt unregistered [ 7328.875477] Key type ._llcrypt unregistered [ 7329.146347] Key type ._llcrypt registered [ 7329.148053] Key type .llcrypt registered [ 7329.306719] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7329.311329] alg: No test for adler32 (adler32-zlib) [ 7330.161584] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7330.238866] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7331.815102] Key type lgssc registered [ 7332.147299] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7335.382108] Lustre: DEBUG MARKER: Iteration 27 [ 7335.483064] LustreError: 338127:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7335.483444] LustreError: 338128:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7335.487984] LustreError: 338127:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7336.539186] Lustre: Mounted lustre-client [ 7337.060248] LustreError: 338235:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916410c23000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7337.063533] LustreError: 338234:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 7337.064121] LustreError: 338235:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7337.084070] Lustre: Unmounted lustre-client [ 7337.909137] Key type lgssc unregistered [ 7338.016462] LNet: 338476:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7338.018797] LNetError: 338476:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7338.028618] LNet: Removed LNI 192.168.203.56@tcp [ 7338.223082] Key type .llcrypt unregistered [ 7338.224050] Key type ._llcrypt unregistered [ 7338.455651] Key type ._llcrypt registered [ 7338.457107] Key type .llcrypt registered [ 7338.644098] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7338.649631] alg: No test for adler32 (adler32-zlib) [ 7339.494114] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7339.570434] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7341.151082] Key type lgssc registered [ 7341.482717] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7344.813389] Lustre: DEBUG MARKER: Iteration 28 [ 7344.907333] LustreError: 339294:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7344.909065] LustreError: 339295:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7344.911846] LustreError: 339294:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7345.962382] Lustre: Mounted lustre-client [ 7345.963872] Lustre: Skipped 1 previous similar message [ 7346.467295] LustreError: 339398:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9164057eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7346.470825] LustreError: 339398:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7346.474570] LustreError: 339397:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 7346.476761] LustreError: 339397:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7346.490111] Lustre: Unmounted lustre-client [ 7347.428679] Key type lgssc unregistered [ 7347.539437] LNet: 339639:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7347.542115] LNetError: 339639:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7347.548678] LNet: Removed LNI 192.168.203.56@tcp [ 7347.769136] Key type .llcrypt unregistered [ 7347.770972] Key type ._llcrypt unregistered [ 7347.982415] Key type ._llcrypt registered [ 7347.983883] Key type .llcrypt registered [ 7348.175760] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7348.180137] alg: No test for adler32 (adler32-zlib) [ 7349.027165] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7349.099493] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7350.679149] Key type lgssc registered [ 7351.017455] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7354.573362] Lustre: DEBUG MARKER: Iteration 29 [ 7354.685691] LustreError: 340457:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7354.685776] LustreError: 340456:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7354.690679] LustreError: 340457:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7355.744203] Lustre: Mounted lustre-client [ 7356.319361] LustreError: 340563:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916411631800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7356.322545] LustreError: 340562:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7356.323079] LustreError: 340563:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7356.349900] Lustre: Unmounted lustre-client [ 7357.258840] Key type lgssc unregistered [ 7357.373378] LNet: 340804:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7357.376055] LNetError: 340804:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7357.382677] LNet: Removed LNI 192.168.203.56@tcp [ 7357.607096] Key type .llcrypt unregistered [ 7357.608321] Key type ._llcrypt unregistered [ 7357.872914] Key type ._llcrypt registered [ 7357.873716] Key type .llcrypt registered [ 7358.036994] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7358.041897] alg: No test for adler32 (adler32-zlib) [ 7358.918748] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7359.010275] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7360.591112] Key type lgssc registered [ 7361.016763] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7364.084428] Lustre: DEBUG MARKER: Iteration 30 [ 7364.174520] LustreError: 341624:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7364.174545] LustreError: 341623:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7364.178540] LustreError: 341624:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7365.220638] Lustre: Mounted lustre-client [ 7365.221730] Lustre: Skipped 1 previous similar message [ 7365.628253] LustreError: 341724:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916425607800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7365.630313] LustreError: 341725:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7365.631767] LustreError: 341724:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7365.645699] Lustre: Unmounted lustre-client [ 7366.507388] Key type lgssc unregistered [ 7366.621381] LNet: 341966:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7366.623880] LNetError: 341966:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7366.633630] LNet: Removed LNI 192.168.203.56@tcp [ 7366.911168] Key type .llcrypt unregistered [ 7366.912984] Key type ._llcrypt unregistered [ 7367.275943] Key type ._llcrypt registered [ 7367.277354] Key type .llcrypt registered [ 7367.511153] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7367.516645] alg: No test for adler32 (adler32-zlib) [ 7368.385635] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7368.471321] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7370.055122] Key type lgssc registered [ 7370.414620] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7373.614941] Lustre: DEBUG MARKER: Iteration 31 [ 7373.711830] LustreError: 342784:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7373.713988] LustreError: 342785:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7373.715975] LustreError: 342784:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7374.753685] Lustre: Mounted lustre-client [ 7374.755396] Lustre: Skipped 1 previous similar message [ 7375.151276] LustreError: 342887:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916408f43000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7375.154584] LustreError: 342887:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7375.157563] LustreError: 342886:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7375.159675] LustreError: 342886:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7375.170297] Lustre: Unmounted lustre-client [ 7376.031878] Key type lgssc unregistered [ 7376.148371] LNet: 343128:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7376.151887] LNetError: 343128:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7376.160529] LNet: Removed LNI 192.168.203.56@tcp [ 7376.377087] Key type .llcrypt unregistered [ 7376.378037] Key type ._llcrypt unregistered [ 7376.594770] Key type ._llcrypt registered [ 7376.596407] Key type .llcrypt registered [ 7376.789620] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7376.795597] alg: No test for adler32 (adler32-zlib) [ 7377.642685] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7377.713132] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7379.287124] Key type lgssc registered [ 7379.632659] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7382.665014] Lustre: DEBUG MARKER: Iteration 32 [ 7382.755871] LustreError: 343945:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7382.756085] LustreError: 343946:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7382.759340] LustreError: 343945:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7383.804905] Lustre: Mounted lustre-client [ 7384.195697] LustreError: 344047:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916410121000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7384.201567] LustreError: 344047:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 7384.244112] Lustre: Unmounted lustre-client [ 7385.057931] Key type lgssc unregistered [ 7385.161435] LNet: 344291:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7385.163306] LNetError: 344291:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7385.170651] LNet: Removed LNI 192.168.203.56@tcp [ 7385.362102] Key type .llcrypt unregistered [ 7385.363512] Key type ._llcrypt unregistered [ 7385.570452] Key type ._llcrypt registered [ 7385.573680] Key type .llcrypt registered [ 7385.732780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7385.742565] alg: No test for adler32 (adler32-zlib) [ 7386.585270] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7386.660960] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7388.239130] Key type lgssc registered [ 7388.582501] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7391.735258] Lustre: DEBUG MARKER: Iteration 33 [ 7391.835398] LustreError: 345108:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7391.835453] LustreError: 345107:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7391.840319] LustreError: 345108:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7392.883246] Lustre: Mounted lustre-client [ 7392.883246] Lustre: Mounted lustre-client [ 7393.281047] LustreError: 345215:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91641a69f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7393.285850] LustreError: 345215:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7393.288014] LustreError: 345215:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7393.296082] Lustre: Unmounted lustre-client [ 7394.116925] Key type lgssc unregistered [ 7394.232438] LNet: 345456:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7394.234978] LNetError: 345456:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7394.245680] LNet: Removed LNI 192.168.203.56@tcp [ 7394.447090] Key type .llcrypt unregistered [ 7394.447892] Key type ._llcrypt unregistered [ 7394.709611] Key type ._llcrypt registered [ 7394.710497] Key type .llcrypt registered [ 7394.874032] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7394.878666] alg: No test for adler32 (adler32-zlib) [ 7395.723397] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7395.799285] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7397.375144] Key type lgssc registered [ 7397.715265] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7401.150166] Lustre: DEBUG MARKER: Iteration 34 [ 7401.254507] LustreError: 346274:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7401.254546] LustreError: 346275:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7401.260374] LustreError: 346274:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7402.313093] Lustre: Mounted lustre-client [ 7402.314086] Lustre: Skipped 1 previous similar message [ 7402.715238] LustreError: 346385:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916404d7f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7402.720154] LustreError: 346385:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7402.724314] LustreError: 346384:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7402.751028] Lustre: Unmounted lustre-client [ 7402.751871] Lustre: Skipped 1 previous similar message [ 7403.610253] Key type lgssc unregistered [ 7403.727444] LNet: 346626:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7403.730789] LNetError: 346626:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7403.737865] LNet: Removed LNI 192.168.203.56@tcp [ 7403.951096] Key type .llcrypt unregistered [ 7403.952103] Key type ._llcrypt unregistered [ 7404.210199] Key type ._llcrypt registered [ 7404.212474] Key type .llcrypt registered [ 7404.367916] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7404.372584] alg: No test for adler32 (adler32-zlib) [ 7405.218953] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7405.292369] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7406.871115] Key type lgssc registered [ 7407.197944] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7410.720599] Lustre: DEBUG MARKER: Iteration 35 [ 7410.838370] LustreError: 347443:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7410.838796] LustreError: 347444:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7410.843209] LustreError: 347443:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7411.899177] Lustre: Mounted lustre-client [ 7412.414656] LustreError: 347547:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916408ad2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7412.416502] LustreError: 347548:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7412.418079] LustreError: 347547:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7412.462105] Lustre: Unmounted lustre-client [ 7413.379499] Key type lgssc unregistered [ 7413.480321] LNet: 347790:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7413.482344] LNetError: 347790:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7413.488539] LNet: Removed LNI 192.168.203.56@tcp [ 7413.709103] Key type .llcrypt unregistered [ 7413.710331] Key type ._llcrypt unregistered [ 7413.957590] Key type ._llcrypt registered [ 7413.958730] Key type .llcrypt registered [ 7414.125708] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7414.129994] alg: No test for adler32 (adler32-zlib) [ 7414.979777] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7415.056991] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7416.639128] Key type lgssc registered [ 7416.995701] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7420.165810] Lustre: DEBUG MARKER: Iteration 36 [ 7420.255342] LustreError: 348607:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7420.255387] LustreError: 348608:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7420.259805] LustreError: 348607:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7421.292362] Lustre: Mounted lustre-client [ 7421.710691] LustreError: 348708:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916411146000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7421.712805] LustreError: 348707:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7421.713662] LustreError: 348708:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7421.737682] Lustre: Unmounted lustre-client [ 7421.739068] Lustre: Skipped 1 previous similar message [ 7422.576292] Key type lgssc unregistered [ 7422.692481] LNet: 348951:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7422.694416] LNetError: 348951:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7422.701479] LNet: Removed LNI 192.168.203.56@tcp [ 7422.910081] Key type .llcrypt unregistered [ 7422.910913] Key type ._llcrypt unregistered [ 7423.168348] Key type ._llcrypt registered [ 7423.169594] Key type .llcrypt registered [ 7423.316618] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7423.321407] alg: No test for adler32 (adler32-zlib) [ 7424.165107] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7424.236130] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7425.815135] Key type lgssc registered [ 7426.147490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7429.207487] Lustre: DEBUG MARKER: Iteration 37 [ 7429.292505] LustreError: 349768:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7429.292547] LustreError: 349769:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7429.298121] LustreError: 349768:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7430.351796] Lustre: Mounted lustre-client [ 7430.353226] Lustre: Skipped 1 previous similar message [ 7430.772848] LustreError: 349877:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916403147800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7430.775393] LustreError: 349877:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7430.777851] LustreError: 349878:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7430.780257] LustreError: 349878:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7430.788086] Lustre: Unmounted lustre-client [ 7431.638668] Key type lgssc unregistered [ 7431.737454] LNet: 350119:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7431.740421] LNetError: 350119:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7431.751550] LNet: Removed LNI 192.168.203.56@tcp [ 7431.967090] Key type .llcrypt unregistered [ 7431.967885] Key type ._llcrypt unregistered [ 7432.241091] Key type ._llcrypt registered [ 7432.242611] Key type .llcrypt registered [ 7432.396396] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7432.401534] alg: No test for adler32 (adler32-zlib) [ 7433.243688] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7433.315847] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7434.895141] Key type lgssc registered [ 7435.233743] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7438.287569] Lustre: DEBUG MARKER: Iteration 38 [ 7438.376101] LustreError: 350936:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7438.376303] LustreError: 350937:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7438.379550] LustreError: 350936:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7439.417851] Lustre: Mounted lustre-client [ 7439.818546] LustreError: 351043:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916419b58800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7439.821729] LustreError: 351044:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 7439.822833] LustreError: 351043:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7439.840084] Lustre: Unmounted lustre-client [ 7440.709736] Key type lgssc unregistered [ 7440.819677] LNet: 351285:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7440.822559] LNetError: 351285:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7440.830579] LNet: Removed LNI 192.168.203.56@tcp [ 7441.033089] Key type .llcrypt unregistered [ 7441.034472] Key type ._llcrypt unregistered [ 7441.258037] Key type ._llcrypt registered [ 7441.258991] Key type .llcrypt registered [ 7441.435937] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7441.441037] alg: No test for adler32 (adler32-zlib) [ 7442.299210] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7442.376480] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7443.959132] Key type lgssc registered [ 7444.314788] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7447.514750] Lustre: DEBUG MARKER: Iteration 39 [ 7447.602647] LustreError: 352103:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7447.602693] LustreError: 352104:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7447.606553] LustreError: 352103:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7448.648578] Lustre: Mounted lustre-client [ 7449.059800] LustreError: 352213:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916404d7f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7449.062150] LustreError: 352214:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 7449.063834] LustreError: 352213:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7449.079596] Lustre: Unmounted lustre-client [ 7449.979584] Key type lgssc unregistered [ 7450.090464] LNet: 352455:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7450.093337] LNetError: 352455:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7450.101667] LNet: Removed LNI 192.168.203.56@tcp [ 7450.305096] Key type .llcrypt unregistered [ 7450.306314] Key type ._llcrypt unregistered [ 7450.556636] Key type ._llcrypt registered [ 7450.557550] Key type .llcrypt registered [ 7450.724537] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7450.728965] alg: No test for adler32 (adler32-zlib) [ 7451.572254] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7451.648266] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7453.231121] Key type lgssc registered [ 7453.580081] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7456.681665] Lustre: DEBUG MARKER: Iteration 40 [ 7456.768979] LustreError: 353274:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7456.769031] LustreError: 353275:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7456.773274] LustreError: 353274:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7457.813353] Lustre: Mounted lustre-client [ 7457.814989] Lustre: Skipped 1 previous similar message [ 7458.211954] LustreError: 353382:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916408f44000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7458.214233] LustreError: 353381:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7458.217272] LustreError: 353382:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7458.239612] Lustre: Unmounted lustre-client [ 7459.120579] Key type lgssc unregistered [ 7459.238499] LNet: 353624:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7459.241248] LNetError: 353624:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7459.248632] LNet: Removed LNI 192.168.203.56@tcp [ 7459.454105] Key type .llcrypt unregistered [ 7459.454917] Key type ._llcrypt unregistered [ 7459.690511] Key type ._llcrypt registered [ 7459.694138] Key type .llcrypt registered [ 7459.887304] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7459.892853] alg: No test for adler32 (adler32-zlib) [ 7460.749758] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7460.831296] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7462.415141] Key type lgssc registered [ 7462.770057] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7465.970335] Lustre: DEBUG MARKER: Iteration 41 [ 7466.065263] LustreError: 354441:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7466.065321] LustreError: 354442:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7466.069740] LustreError: 354441:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7467.108166] Lustre: Mounted lustre-client [ 7467.110268] Lustre: Skipped 1 previous similar message [ 7467.525134] LustreError: 354545:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916411144800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7467.529759] LustreError: 354546:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7467.530273] LustreError: 354545:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7467.549922] Lustre: Unmounted lustre-client [ 7468.461870] Key type lgssc unregistered [ 7468.565422] LNet: 354787:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7468.568941] LNetError: 354787:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7468.575714] LNet: Removed LNI 192.168.203.56@tcp [ 7468.785113] Key type .llcrypt unregistered [ 7468.786289] Key type ._llcrypt unregistered [ 7469.033589] Key type ._llcrypt registered [ 7469.036113] Key type .llcrypt registered [ 7469.221639] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7469.226350] alg: No test for adler32 (adler32-zlib) [ 7470.069084] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7470.141952] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7471.719110] Key type lgssc registered [ 7472.052180] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7475.054691] Lustre: DEBUG MARKER: Iteration 42 [ 7475.142078] LustreError: 355605:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7475.142181] LustreError: 355606:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7475.145947] LustreError: 355605:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7476.190358] Lustre: Mounted lustre-client [ 7476.579962] LustreError: 355716:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91640673b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7476.582609] LustreError: 355717:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7476.584971] LustreError: 355716:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7476.603038] Lustre: Unmounted lustre-client [ 7477.474980] Key type lgssc unregistered [ 7477.587503] LNet: 355959:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7477.590443] LNetError: 355959:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7477.599632] LNet: Removed LNI 192.168.203.56@tcp [ 7477.818126] Key type .llcrypt unregistered [ 7477.819518] Key type ._llcrypt unregistered [ 7478.030255] Key type ._llcrypt registered [ 7478.031931] Key type .llcrypt registered [ 7478.231433] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7478.235931] alg: No test for adler32 (adler32-zlib) [ 7479.085144] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7479.165163] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7480.743140] Key type lgssc registered [ 7481.088017] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7484.050295] Lustre: DEBUG MARKER: Iteration 43 [ 7484.129777] LustreError: 356776:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7484.132190] LustreError: 356777:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7484.134050] LustreError: 356776:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7485.171930] Lustre: Mounted lustre-client [ 7485.549976] LustreError: 356885:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916410864800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7485.552921] LustreError: 356885:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7485.555400] LustreError: 356886:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7485.557307] LustreError: 356886:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7485.578073] Lustre: Unmounted lustre-client [ 7486.366828] Key type lgssc unregistered [ 7486.467432] LNet: 357127:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7486.470887] LNetError: 357127:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7486.481586] LNet: Removed LNI 192.168.203.56@tcp [ 7486.695085] Key type .llcrypt unregistered [ 7486.696061] Key type ._llcrypt unregistered [ 7486.901487] Key type ._llcrypt registered [ 7486.902371] Key type .llcrypt registered [ 7487.118611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7487.122886] alg: No test for adler32 (adler32-zlib) [ 7487.977177] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7488.062790] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7489.647157] Key type lgssc registered [ 7490.102256] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7493.653838] Lustre: DEBUG MARKER: Iteration 44 [ 7493.755991] LustreError: 357947:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7493.757150] LustreError: 357948:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7493.761301] LustreError: 357947:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7494.814737] Lustre: Mounted lustre-client [ 7494.816466] Lustre: Skipped 1 previous similar message [ 7495.297145] LustreError: 358048:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916410c24000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7495.300369] LustreError: 358049:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7495.301461] LustreError: 358048:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7495.305784] LustreError: 358049:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7495.325463] Lustre: Unmounted lustre-client [ 7495.326368] Lustre: Skipped 1 previous similar message [ 7496.271082] Key type lgssc unregistered [ 7496.385568] LNet: 358289:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7496.387757] LNetError: 358289:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7496.397619] LNet: Removed LNI 192.168.203.56@tcp [ 7496.634113] Key type .llcrypt unregistered [ 7496.635280] Key type ._llcrypt unregistered [ 7496.901306] Key type ._llcrypt registered [ 7496.910560] Key type .llcrypt registered [ 7497.115818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7497.120989] alg: No test for adler32 (adler32-zlib) [ 7497.999502] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7498.096434] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7499.679149] Key type lgssc registered [ 7500.124362] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7503.639054] Lustre: DEBUG MARKER: Iteration 45 [ 7503.745120] LustreError: 359107:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7503.745354] LustreError: 359108:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7503.749574] LustreError: 359107:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7504.793971] Lustre: Mounted lustre-client [ 7505.231372] LustreError: 359216:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91640673f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7505.234496] LustreError: 359215:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7505.235441] LustreError: 359216:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7505.255234] Lustre: Unmounted lustre-client [ 7506.173439] Key type lgssc unregistered [ 7506.296312] LNet: 359457:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7506.298329] LNetError: 359457:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7506.305825] LNet: Removed LNI 192.168.203.56@tcp [ 7506.538100] Key type .llcrypt unregistered [ 7506.539404] Key type ._llcrypt unregistered [ 7506.823467] Key type ._llcrypt registered [ 7506.824986] Key type .llcrypt registered [ 7507.007073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7507.011674] alg: No test for adler32 (adler32-zlib) [ 7507.870742] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7507.952301] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7509.535103] Key type lgssc registered [ 7509.905920] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7513.395857] Lustre: DEBUG MARKER: Iteration 46 [ 7513.502035] LustreError: 360274:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7513.502358] LustreError: 360275:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7513.506969] LustreError: 360274:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7514.560674] Lustre: Mounted lustre-client [ 7514.562479] Lustre: Skipped 1 previous similar message [ 7514.994556] LustreError: 360377:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916410c26800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7514.998850] LustreError: 360378:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7514.999435] LustreError: 360377:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7515.019275] Lustre: Unmounted lustre-client [ 7515.021065] Lustre: Skipped 1 previous similar message [ 7515.914893] Key type lgssc unregistered [ 7516.031356] LNet: 360618:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7516.035192] LNetError: 360618:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7516.046726] LNet: Removed LNI 192.168.203.56@tcp [ 7516.277116] Key type .llcrypt unregistered [ 7516.278135] Key type ._llcrypt unregistered [ 7516.509315] Key type ._llcrypt registered [ 7516.511159] Key type .llcrypt registered [ 7516.672816] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7516.697768] alg: No test for adler32 (adler32-zlib) [ 7517.560786] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7517.644044] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7519.223145] Key type lgssc registered [ 7519.610658] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7523.264374] Lustre: DEBUG MARKER: Iteration 47 [ 7523.372084] LustreError: 361434:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7523.373139] LustreError: 361435:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7523.376369] LustreError: 361434:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7524.423747] Lustre: Mounted lustre-client [ 7524.424619] Lustre: Skipped 1 previous similar message [ 7524.881923] LustreError: 361541:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916418cb1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7524.886237] LustreError: 361541:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7524.888690] LustreError: 361541:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7524.906801] Lustre: Unmounted lustre-client [ 7525.812789] Key type lgssc unregistered [ 7525.930363] LNet: 361784:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7525.933249] LNetError: 361784:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7525.946773] LNet: Removed LNI 192.168.203.56@tcp [ 7526.183129] Key type .llcrypt unregistered [ 7526.184323] Key type ._llcrypt unregistered [ 7526.447922] Key type ._llcrypt registered [ 7526.449337] Key type .llcrypt registered [ 7526.644848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7526.651780] alg: No test for adler32 (adler32-zlib) [ 7527.514716] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7527.601073] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7529.183127] Key type lgssc registered [ 7529.563550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7533.227621] Lustre: DEBUG MARKER: Iteration 48 [ 7533.334185] LustreError: 362602:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7533.334228] LustreError: 362603:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7533.338603] LustreError: 362602:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7534.392630] Lustre: Mounted lustre-client [ 7534.834607] LustreError: 362706:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916433c6a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7534.839181] LustreError: 362706:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 7534.876099] Lustre: Unmounted lustre-client [ 7534.877346] Lustre: Skipped 1 previous similar message [ 7535.828791] Key type lgssc unregistered [ 7535.935376] LNet: 362948:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7535.937972] LNetError: 362948:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7535.944765] LNet: Removed LNI 192.168.203.56@tcp [ 7536.175101] Key type .llcrypt unregistered [ 7536.176193] Key type ._llcrypt unregistered [ 7536.400712] Key type ._llcrypt registered [ 7536.401702] Key type .llcrypt registered [ 7536.639099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7536.653341] alg: No test for adler32 (adler32-zlib) [ 7537.509524] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7537.597919] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7539.183091] Key type lgssc registered [ 7539.617774] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7543.271961] Lustre: DEBUG MARKER: Iteration 49 [ 7543.366209] LustreError: 363765:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7543.366276] LustreError: 363766:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7543.371655] LustreError: 363765:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7544.419392] Lustre: Mounted lustre-client [ 7544.420554] Lustre: Skipped 1 previous similar message [ 7544.862091] LustreError: 363873:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916404d7e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7544.866053] LustreError: 363873:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7544.866342] LustreError: 363872:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7544.869886] LustreError: 363872:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7544.887101] Lustre: Unmounted lustre-client [ 7544.888435] Lustre: Skipped 1 previous similar message [ 7545.788658] Key type lgssc unregistered [ 7545.899563] LNet: 364114:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7545.901657] LNetError: 364114:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7545.908733] LNet: Removed LNI 192.168.203.56@tcp [ 7546.143105] Key type .llcrypt unregistered [ 7546.144586] Key type ._llcrypt unregistered [ 7546.400247] Key type ._llcrypt registered [ 7546.402087] Key type .llcrypt registered [ 7546.601729] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7546.607486] alg: No test for adler32 (adler32-zlib) [ 7547.462659] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7547.543209] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7549.127170] Key type lgssc registered [ 7549.493669] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7553.282886] Lustre: DEBUG MARKER: Iteration 50 [ 7553.380084] LustreError: 364931:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 7553.381607] LustreError: 364932:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 7553.385707] LustreError: 364931:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7554.426038] Lustre: Mounted lustre-client [ 7554.428227] Lustre: Skipped 1 previous similar message [ 7554.936707] LustreError: 365038:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916420600800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7554.942783] LustreError: 365038:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7554.961247] Lustre: Unmounted lustre-client [ 7555.953043] Key type lgssc unregistered [ 7556.068549] LNet: 365280:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7556.070813] LNetError: 365280:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7556.080420] LNet: Removed LNI 192.168.203.56@tcp [ 7556.308093] Key type .llcrypt unregistered [ 7556.309411] Key type ._llcrypt unregistered [ 7556.542933] Key type ._llcrypt registered [ 7556.544378] Key type .llcrypt registered [ 7556.731976] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7556.738930] alg: No test for adler32 (adler32-zlib) [ 7557.597561] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 7557.677109] LNet: Added LNI 192.168.203.56@tcp [8/256/0/180] [ 7559.255109] Key type lgssc registered [ 7559.618504] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7563.888544] Lustre: Mounted lustre-client [ 7566.002443] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 03:39:46 (1769762386) [ 7582.687156] Lustre: 366607:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762387/real 1769762387] req@ffff91642f2fe300 x1855730355283200/t0(0) o36->lustre-MDT0000-mdc-ffff916411188000@192.168.203.156@tcp:12/10 lens 496/440 e 0 to 1 dl 1769762403 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7582.693718] Lustre: lustre-MDT0000-mdc-ffff916411188000: Connection to lustre-MDT0000 (at 192.168.203.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7582.702520] Lustre: lustre-MDT0000-mdc-ffff916411188000: Connection restored to 192.168.203.156@tcp (at 192.168.203.156@tcp) [ 7599.071095] Lustre: 366607:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762404/real 1769762404] req@ffff91642f2fe300 x1855730355283200/t0(0) o36->lustre-MDT0000-mdc-ffff916411188000@192.168.203.156@tcp:12/10 lens 496/440 e 0 to 1 dl 1769762420 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7599.078054] Lustre: lustre-MDT0000-mdc-ffff916411188000: Connection to lustre-MDT0000 (at 192.168.203.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7599.086331] Lustre: lustre-MDT0000-mdc-ffff916411188000: Connection restored to 192.168.203.156@tcp (at 192.168.203.156@tcp) [ 7615.455186] Lustre: 366607:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762420/real 1769762420] req@ffff91642f2fe300 x1855730355283200/t0(0) o36->lustre-MDT0000-mdc-ffff916411188000@192.168.203.156@tcp:12/10 lens 496/440 e 0 to 1 dl 1769762436 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7615.465136] Lustre: lustre-MDT0000-mdc-ffff916411188000: Connection to lustre-MDT0000 (at 192.168.203.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7615.478266] Lustre: lustre-MDT0000-mdc-ffff916411188000: Connection restored to 192.168.203.156@tcp (at 192.168.203.156@tcp) [ 7630.815125] Lustre: 366607:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762436/real 1769762436] req@ffff91642f2fe300 x1855730355283200/t0(0) o36->lustre-MDT0000-mdc-ffff916411188000@192.168.203.156@tcp:12/10 lens 496/440 e 0 to 1 dl 1769762452 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7630.823336] Lustre: lustre-MDT0000-mdc-ffff916411188000: Connection to lustre-MDT0000 (at 192.168.203.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7630.832491] Lustre: lustre-MDT0000-mdc-ffff916411188000: Connection restored to 192.168.203.156@tcp (at 192.168.203.156@tcp) [ 7631.349541] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 03:40:52 (1769762452) [ 7631.865665] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 7632.432308] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 03:40:53 (1769762453) [ 7632.950612] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 7633.569320] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 03:40:54 (1769762454) [ 7635.824231] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 03:40:56 (1769762456) [ 7636.333197] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 7636.878155] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 03:40:57 (1769762457) [ 7648.816084] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 03:41:09 (1769762469) [ 7649.275525] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 7649.817803] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 03:41:10 (1769762470) [ 7649.900619] LustreError: 369763:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 7651.983134] LustreError: 369763:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 7654.102019] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 03:41:14 (1769762474) [ 7671.775133] Lustre: 365468:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762477/real 1769762477] req@ffff91643ebed880 x1855730356088960/t0(0) o4->lustre-OST0000-osc-ffff916411188000@192.168.203.156@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769762493 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7671.775173] Lustre: lustre-OST0000-osc-ffff916411188000: Connection to lustre-OST0000 (at 192.168.203.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7671.785920] Lustre: 365468:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 7671.795693] Lustre: lustre-OST0000-osc-ffff916411188000: Connection restored to 192.168.203.156@tcp (at 192.168.203.156@tcp) [ 7688.159158] Lustre: 365469:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762493/real 1769762493] req@ffff916425660e00 x1855730356088576/t0(0) o4->lustre-OST0000-osc-ffff916411188000@192.168.203.156@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769762509 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7688.159217] Lustre: lustre-OST0000-osc-ffff916411188000: Connection to lustre-OST0000 (at 192.168.203.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7688.166379] Lustre: 365469:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [ 7688.175050] Lustre: lustre-OST0000-osc-ffff916411188000: Connection restored to 192.168.203.156@tcp (at 192.168.203.156@tcp) [ 7704.351200] Lustre: 365470:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762509/real 1769762509] req@ffff9164054c2d80 x1855730356088192/t0(0) o4->lustre-OST0000-osc-ffff916411188000@192.168.203.156@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769762525 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7704.361147] Lustre: lustre-OST0000-osc-ffff916411188000: Connection to lustre-OST0000 (at 192.168.203.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7704.371434] Lustre: lustre-OST0000-osc-ffff916411188000: Connection restored to 192.168.203.156@tcp (at 192.168.203.156@tcp) [ 7743.133537] Lustre: DEBUG MARKER: oleg356-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff916411188000.ost_server_uuid 50 [ 7743.615197] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff916411188000.ost_server_uuid in FULL state after 0 sec [ 7744.126488] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 7744.666490] Lustre: DEBUG MARKER: == sanityn test complete, duration 7472 sec ============== 03:42:45 (1769762565) [ 7745.208577] Lustre: DEBUG MARKER: === sanityn: start cleanup 03:42:46 (1769762566) === [ 7789.151633] LustreError: 371754:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916420606000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7789.157804] LustreError: 371754:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7789.169085] Lustre: Unmounted lustre-client [ 7790.308269] Lustre: DEBUG MARKER: === sanityn: finish cleanup 03:43:31 (1769762611) === [ 7790.627290] LustreError: 372055:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff916411188000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7790.630467] LustreError: 372055:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7790.636614] LustreError: 372055:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7790.638995] LustreError: 372055:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 7790.664079] Lustre: Unmounted lustre-client [ 7806.496713] Key type lgssc unregistered [ 7806.616777] LNet: 372645:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7806.620444] LNetError: 372645:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7806.626764] LNet: Removed LNI 192.168.203.56@tcp [ 7806.889102] Key type .llcrypt unregistered [ 7806.890108] Key type ._llcrypt unregistered