[ 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 515102981 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, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001017] APIC: Switch to symmetric I/O mode setup [ 0.003062] x2apic enabled [ 0.004012] Switched APIC routing to physical x2apic. [ 0.005017] kvm-guest: setup PV IPIs [ 0.008000] ..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.008029] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010010] pid_max: default: 32768 minimum: 301 [ 0.011152] LSM: Security Framework initializing [ 0.012066] Yama: becoming mindful. [ 0.013051] SELinux: Initializing. [ 0.014126] *** VALIDATE selinux *** [ 0.023195] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027909] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028204] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030018] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031104] *** VALIDATE tmpfs *** [ 0.033076] *** VALIDATE proc *** [ 0.034280] *** VALIDATE cgroup *** [ 0.035011] *** VALIDATE cgroup2 *** [ 0.037052] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038163] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040032] Spectre V2 : User space: Vulnerable [ 0.041009] Speculative Store Bypass: Vulnerable [ 0.044459] debug: unmapping init [mem 0xffffffffb0a59000-0xffffffffb0a60fff] [ 0.047117] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048687] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049027] ... version: 2 [ 0.050012] ... bit width: 48 [ 0.051013] ... generic registers: 4 [ 0.052010] ... value mask: 0000ffffffffffff [ 0.053012] ... max period: 00007fffffffffff [ 0.054015] ... fixed-purpose events: 3 [ 0.055011] ... event mask: 000000070000000f [ 0.056324] rcu: Hierarchical SRCU implementation. [ 0.058707] smp: Bringing up secondary CPUs ... [ 0.059582] x86: Booting SMP configuration: [ 0.060029] .... node #0, CPUs: #1 #2 #3 [ 0.068187] smp: Brought up 1 node, 4 CPUs [ 0.070012] smpboot: Max logical packages: 1 [ 0.071019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.125835] node 0 deferred pages initialised in 52ms [ 0.128325] devtmpfs: initialized [ 0.129154] x86/mm: Memory block size: 128MB [ 0.131316] gcov: version magic: 0x41383552 [ 0.133297] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.134063] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.135306] pinctrl core: initialized pinctrl subsystem [ 0.136144] [ 0.136443] ************************************************************* [ 0.137011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.138006] ** ** [ 0.139006] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.140007] ** ** [ 0.141007] ** This means that this kernel is built to expose internal ** [ 0.142010] ** IOMMU data structures, which may compromise security on ** [ 0.143008] ** your system. ** [ 0.144007] ** ** [ 0.145011] ** If you see this message and you are not debugging the ** [ 0.146007] ** kernel, report this immediately to your vendor! ** [ 0.147009] ** ** [ 0.148007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.149007] ************************************************************* [ 0.150630] NET: Registered protocol family 16 [ 0.151344] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.152035] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.153038] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.154454] cpuidle: using governor menu [ 0.155662] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.157306] PCI: Using configuration type 1 for base access [ 0.158118] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.165083] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.166018] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.168250] cryptd: max_cpu_qlen set to 1000 [ 0.170186] ACPI: Added _OSI(Module Device) [ 0.171009] ACPI: Added _OSI(Processor Device) [ 0.171829] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.172006] ACPI: Added _OSI(Processor Aggregator Device) [ 0.175188] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.177412] ACPI: Interpreter enabled [ 0.178039] ACPI: PM: (supports S0 S3 S4 S5) [ 0.178826] ACPI: Using IOAPIC for interrupt routing [ 0.179068] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.180258] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.188368] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.189021] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.190009] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.191067] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.192974] acpiphp: Slot [2] registered [ 0.193060] acpiphp: Slot [5] registered [ 0.193917] acpiphp: Slot [6] registered [ 0.194050] acpiphp: Slot [3] registered [ 0.194753] acpiphp: Slot [4] registered [ 0.195046] acpiphp: Slot [7] registered [ 0.196022] acpiphp: Slot [8] registered [ 0.196970] acpiphp: Slot [9] registered [ 0.197048] acpiphp: Slot [10] registered [ 0.197861] acpiphp: Slot [11] registered [ 0.198079] acpiphp: Slot [12] registered [ 0.198897] acpiphp: Slot [13] registered [ 0.199065] acpiphp: Slot [14] registered [ 0.199863] acpiphp: Slot [15] registered [ 0.200092] acpiphp: Slot [16] registered [ 0.201111] acpiphp: Slot [17] registered [ 0.202083] acpiphp: Slot [18] registered [ 0.203034] acpiphp: Slot [19] registered [ 0.203946] acpiphp: Slot [20] registered [ 0.204047] acpiphp: Slot [21] registered [ 0.204857] acpiphp: Slot [22] registered [ 0.205048] acpiphp: Slot [23] registered [ 0.205797] acpiphp: Slot [24] registered [ 0.206082] acpiphp: Slot [25] registered [ 0.206882] acpiphp: Slot [26] registered [ 0.207060] acpiphp: Slot [27] registered [ 0.207888] acpiphp: Slot [28] registered [ 0.208046] acpiphp: Slot [29] registered [ 0.208849] acpiphp: Slot [30] registered [ 0.209079] acpiphp: Slot [31] registered [ 0.209908] PCI host bridge to bus 0000:00 [ 0.210014] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.211018] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.212011] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.213012] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.214017] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.215018] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.216207] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.217653] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.218813] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.221917] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.223041] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.224015] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.225013] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.226012] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.227540] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.228621] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.229041] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.230632] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.232013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.237923] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.238956] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.242248] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.245014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.248015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.255017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.261049] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.264018] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.267020] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.274023] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.282050] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.284439] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.287404] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.291536] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.294244] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.302139] iommu: Default domain type: Passthrough [ 0.303402] SCSI subsystem initialized [ 0.305248] ACPI: bus type USB registered [ 0.307124] usbcore: registered new interface driver usbfs [ 0.309216] usbcore: registered new interface driver hub [ 0.311071] usbcore: registered new device driver usb [ 0.312432] pps_core: LinuxPPS API ver. 1 registered [ 0.314023] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.316057] PTP clock support registered [ 0.318155] EDAC MC: Ver: 3.0.0 [ 0.320174] PCI: Using ACPI for IRQ routing [ 0.321632] NetLabel: Initializing [ 0.323018] NetLabel: domain hash size = 128 [ 0.324008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.326085] NetLabel: unlabeled traffic allowed by default [ 0.327171] vgaarb: loaded [ 0.329252] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.330010] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.337165] clocksource: Switched to clocksource kvm-clock [ 0.435375] VFS: Disk quotas dquot_6.6.0 [ 0.437984] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.439464] *** VALIDATE ramfs *** [ 0.440285] *** VALIDATE hugetlbfs *** [ 0.441297] pnp: PnP ACPI init [ 0.442933] pnp: PnP ACPI: found 6 devices [ 0.456369] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.458594] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.460037] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.461335] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.462903] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.464299] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.466095] NET: Registered protocol family 2 [ 0.467681] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.471049] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.473472] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.478341] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.481546] TCP: Hash tables configured (established 65536 bind 65536) [ 0.484236] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.486403] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.488775] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.490984] NET: Registered protocol family 1 [ 0.492899] RPC: Registered named UNIX socket transport module. [ 0.494329] RPC: Registered udp transport module. [ 0.495665] RPC: Registered tcp transport module. [ 0.496753] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.498449] NET: Registered protocol family 44 [ 0.499556] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.500928] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.502214] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.503726] PCI: CLS 0 bytes, default 64 [ 0.504965] Unpacking initramfs... [ 1.963332] debug: unmapping init [mem 0xffff8c9dfcc64000-0xffff8c9dfffcffff] [ 1.967629] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.969704] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 1.972594] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.511477] Initialise system trusted keyrings [ 2.512849] Key type blacklist registered [ 2.514584] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.523977] zbud: loaded [ 2.526755] *** VALIDATE nfs *** [ 2.527957] *** VALIDATE nfs4 *** [ 2.529411] pstore: using deflate compression [ 2.533275] Platform Keyring initialized [ 2.646549] NET: Registered protocol family 38 [ 2.648421] Key type asymmetric registered [ 2.650126] Asymmetric key parser 'x509' registered [ 2.651917] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.655246] io scheduler mq-deadline registered [ 2.656971] io scheduler kyber registered [ 2.658482] io scheduler bfq registered [ 2.660121] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.663383] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.665987] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.669927] ACPI: Power Button [PWRF] [ 2.675275] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.681519] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.694710] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.723584] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.752996] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.758142] Non-volatile memory driver v1.3 [ 2.759861] Linux agpgart interface v0.103 [ 2.792876] virtio_blk virtio1: [vda] 134720 512-byte logical blocks (69.0 MB/65.8 MiB) [ 2.795708] vda: detected capacity change from 0 to 68976640 [ 2.813608] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.816333] vdb: detected capacity change from 0 to 1073741824 [ 2.822578] libphy: Fixed MDIO Bus: probed [ 2.832750] usbcore: registered new interface driver usbserial_generic [ 2.835610] usbserial: USB Serial support registered for generic [ 2.837964] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.842426] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.844300] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.846901] mousedev: PS/2 mouse device common for all mice [ 2.849295] rtc_cmos 00:05: RTC can wake from S4 [ 2.851954] rtc_cmos 00:05: registered as rtc0 [ 2.853536] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.857072] intel_pstate: CPU model not supported [ 2.861252] hid: raw HID events driver (C) Jiri Kosina [ 2.863355] usbcore: registered new interface driver usbhid [ 2.866088] usbhid: USB HID core driver [ 2.868375] drop_monitor: Initializing network drop monitor service [ 2.868669] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.874254] Initializing XFRM netlink socket [ 2.884682] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.887355] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.893411] NET: Registered protocol family 10 [ 2.901620] Segment Routing with IPv6 [ 2.903146] NET: Registered protocol family 17 [ 2.904611] mpls_gso: MPLS GSO support [ 2.910271] RAS: Correctable Errors collector initialized. [ 2.912243] AVX version of gcm_enc/dec engaged. [ 2.914056] AES CTR mode by8 optimization enabled [ 2.997728] sched_clock: Marking stable (2997708811, 0)->(4036300496, -1038591685) [ 3.002766] registered taskstats version 1 [ 3.005436] Loading compiled-in X.509 certificates [ 3.009388] zswap: loaded using pool lzo/zbud [ 3.044911] Key type big_key registered [ 3.059478] Key type encrypted registered [ 3.060959] ima: No TPM chip found, activating TPM-bypass! [ 3.062759] ima: Allocated hash algorithm: sha1 [ 3.064912] ima: No architecture policies found [ 3.066814] evm: Initialising EVM extended attributes: [ 3.069107] evm: security.selinux [ 3.070386] evm: security.ima [ 3.071680] evm: security.capability [ 3.072992] evm: HMAC attrs: 0x1 [ 3.075622] rtc_cmos 00:05: setting system clock to 2026-03-02 06:46:56 UTC (1772434016) [ 3.083626] debug: unmapping init [mem 0xffffffffb1a03000-0xffffffffb1bfffff] [ 3.086916] debug: unmapping init [mem 0xffffffffb0782000-0xffffffffb0a58fff] [ 3.099310] Write protecting the kernel read-only data: 28672k [ 3.105181] debug: unmapping init [mem 0xffffffffaee03000-0xffffffffaeffffff] [ 3.109948] debug: unmapping init [mem 0xffffffffaf714000-0xffffffffaf7fffff] [ 3.156633] 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.168584] systemd[1]: Detected virtualization kvm. [ 3.170775] systemd[1]: Detected architecture x86-64. [ 3.172935] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.206900] systemd[1]: No hostname configured. [ 3.208966] systemd[1]: Set hostname to . [ 3.211441] random: systemd: uninitialized urandom read (16 bytes read) [ 3.213899] systemd[1]: Initializing machine ID from random generator. [ 3.521772] random: systemd: uninitialized urandom read (16 bytes read) [ 3.523907] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.532163] random: systemd: uninitialized urandom read (16 bytes read) [ 3.534032] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.540403] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Timers. [ OK ] Reached target Swap. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket. Starting Journal Service... Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.650385] device-mapper: uevent: version 1.0.3 [ 4.652571] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK [ 5.643836] virtio_net virtio0 ens2: renamed from eth0 ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.664045] random: fast init done [ 5.837575] scsi host0: ata_piix [ 5.922761] scsi host1: ata_piix [ 5.924454] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.930344] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 11.599213] random: crng init done [ 11.605309] random: 7 urandom warning(s) missed due to ratelimiting [ 13.791531] dracut-initqueue[568]: RTNETLINK answers: File exists 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... [ 15.929115] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ 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 Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Slices. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 17.464676] printk: systemd: 19 output lines suppressed due to ratelimiting [ 17.944126] SELinux: Disabled at runtime. [ 18.008934] 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) [ 18.017900] systemd[1]: Detected virtualization kvm. [ 18.019968] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 19.376232] systemd[1]: initrd-switch-root.service: Succeeded. [ 19.385293] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 19.398657] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 19.401950] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 19.404950] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 19.462198] systemd[1]: Starting Journal Service... Starting Journal Service... [ 19.468212] systemd[1]: Stopped target Switch Root. [ OK ] Stopped target Switch Root. Mounting Kernel Debug File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting POSIX Message Queue File System... [ OK ] Listening on RPCbind Server Activation Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-getty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Initrd Root File System. Starting Apply Kernel Variables... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Initrd File Systems. Starting Remount Root and Kernel File Systems... [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Mounting Huge Pages File System... [ OK ] Reached target RPC Port Mapper. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System.[ 20.208364] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 20.786685] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Started udev Kernel Device Manager. [ OK ] Mounted /home/green/git/lustre-release. [ 22.076622] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 22.100855] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 23.079994] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 23.116193] EDAC sbridge: Ver: 1.1.2 [ 25.560602] Key type dns_resolver registered [ 25.907962] NFS: Registering the id_resolver key type [ 25.910184] Key type id_resolver registered [ 25.911861] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Login Service. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg144-client login: [ 91.798753] libcfs: loading out-of-tree module taints kernel. [ 92.126925] Key type ._llcrypt registered [ 92.136712] Key type .llcrypt registered [ 92.872214] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 92.892610] alg: No test for adler32 (adler32-zlib) [ 94.360866] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 95.500588] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 97.392224] Key type lgssc registered [ 99.437066] Lustre: Echo OBD driver; http://www.lustre.org/ [ 174.618796] hrtimer: interrupt took 6009341 ns [ 247.862200] Lustre: Mounted lustre-client [ 253.397621] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 272.571228] Lustre: DEBUG MARKER: oleg144-client.virtnet: executing check_logdir /tmp/testlogs/ [ 273.376338] Lustre: lustre-OST0000-osc-ffff8c9e47216800: disconnect after 23s idle [ 278.762350] Lustre: DEBUG MARKER: oleg144-client.virtnet: executing yml_node [ 284.518909] Lustre: DEBUG MARKER: Client: 2.17.50.193 [ 287.584278] Lustre: DEBUG MARKER: MDS: 2.17.50.193 [ 290.488963] Lustre: DEBUG MARKER: OSS: 2.17.50.193 [ 292.144152] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Mon Mar 2 01:51:44 EST 2026 [ 312.733932] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 314.582739] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 316.346409] Lustre: DEBUG MARKER: === sanityn: start setup 01:52:08 (1772434328) === [ 317.541249] Lustre: Mounted lustre-client [ 322.636639] Lustre: DEBUG MARKER: oleg144-client.virtnet: executing check_config_client /mnt/lustre [ 341.230445] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 355.406711] Lustre: DEBUG MARKER: === sanityn: finish setup 01:52:46 (1772434366) === [ 358.691889] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 01:52:50 (1772434370) [ 370.269812] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 01:53:02 (1772434382) [ 378.257410] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 01:53:10 (1772434390) [ 386.101894] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 01:53:17 (1772434397) [ 392.648191] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 01:53:24 (1772434404) [ 400.944083] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 01:53:32 (1772434412) [ 408.241773] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 01:53:40 (1772434420) [ 415.499260] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 01:53:47 (1772434427) [ 417.127440] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 419.470271] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 01:53:51 (1772434431) [ 427.478349] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 01:53:59 (1772434439) [ 434.908130] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 01:54:06 (1772434446) [ 440.809626] Lustre: lustre-OST0001-osc-ffff8c9e47216800: disconnect after 21s idle [ 440.829223] Lustre: Skipped 1 previous similar message [ 444.323869] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 01:54:16 (1772434456) [ 452.172431] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 01:54:24 (1772434464) [ 456.161287] Lustre: lustre-OST0000-osc-ffff8c9e49fec800: disconnect after 20s idle [ 462.786630] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 01:54:33 (1772434473) [ 471.985833] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 01:54:43 (1772434483) [ 476.642067] Lustre: lustre-OST0001-osc-ffff8c9e47216800: disconnect after 21s idle [ 481.037050] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 01:54:52 (1772434492) [ 490.650595] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 01:55:02 (1772434502) [ 499.297881] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 01:55:11 (1772434511) [ 508.532612] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 01:55:20 (1772434520) [ 517.013595] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 01:55:28 (1772434528) [ 524.950108] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 01:55:36 (1772434536) [ 525.654520] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502302 file: /mnt/lustre/lockdir/lockfile=144115205272502300 [ 672.257385] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 01:58:04 (1772434684) [ 682.870786] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 01:58:14 (1772434694) [ 690.734749] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 01:58:22 (1772434702) [ 698.685824] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 01:58:30 (1772434710) [ 706.971734] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 01:58:38 (1772434718) [ 715.144654] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 01:58:47 (1772434727) [ 717.060530] Lustre: DEBUG MARKER: chmod [ 724.040814] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 01:58:55 (1772434735) [ 761.289944] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7528448KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 779.138196] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 01:59:50 (1772434790) [ 847.827716] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 02:00:59 (1772434859) [ 884.375623] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 02:01:35 (1772434895) [ 887.221815] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 889.115456] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 02:01:40 (1772434900) [ 932.320625] Lustre: lustre-OST0000-osc-ffff8c9e49fec800: disconnect after 20s idle [ 946.248949] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 02:02:38 (1772434958) [ 955.693510] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 02:02:47 (1772434967) [ 957.860921] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 957.965212] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 958.100491] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 958.210907] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 958.368641] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 958.589443] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 958.753397] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 958.883846] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 959.043544] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 959.170765] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 959.368375] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 959.489862] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 959.640167] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 959.774638] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 959.915430] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 960.173598] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 960.364472] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 960.500580] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 960.599946] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 960.668922] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 960.799195] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 960.911225] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 961.030662] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 961.160567] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 961.277529] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 961.481183] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 961.596265] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 961.719885] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 961.886702] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 962.005633] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 962.120531] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 962.231205] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 962.334123] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 962.452914] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 962.584986] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 962.716713] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 962.890781] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 963.009436] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 963.176168] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 963.290497] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 963.425316] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 963.502997] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 963.584512] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 963.664303] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 963.794509] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 963.877827] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 963.977364] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 964.092078] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 964.229476] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 964.340631] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 964.449043] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 964.561622] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 964.677466] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 964.782573] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 964.885811] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 964.990603] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 965.086877] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 965.215231] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 965.315173] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 965.458439] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 965.558463] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 965.644416] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 965.727772] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 965.802939] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 965.902336] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 966.002922] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 966.082594] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 966.201880] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 966.301885] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 966.487783] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 966.605444] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 966.740452] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 966.849405] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 966.940113] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 967.041512] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 967.171056] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 967.282896] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 967.433317] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 967.585291] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 967.707577] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 967.956632] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 968.089710] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 968.160732] Lustre: lustre-OST0001-osc-ffff8c9e49fec800: disconnect after 20s idle [ 968.228701] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 968.416502] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 968.548820] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 968.666100] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 968.783481] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 968.879584] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 968.949270] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 969.054971] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 969.183513] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 969.317336] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 969.469830] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 969.557583] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 969.652888] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 969.773527] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 969.849153] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 969.953776] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 970.075758] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 970.224718] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 970.375716] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 970.527471] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 970.643914] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 970.774422] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 970.875222] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 970.974876] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 971.121379] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 971.253342] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 971.347834] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 971.498504] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 971.681642] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 971.866568] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 971.978680] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 972.156936] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 972.282615] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 972.451596] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 972.666421] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 972.788299] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 972.905469] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 973.018634] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 973.163676] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 973.287447] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 973.494558] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 973.671582] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 973.840194] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 973.972654] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 974.111031] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 974.233752] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 974.350127] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 974.441971] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 974.536405] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 974.643927] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 974.733615] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 974.867337] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 974.989096] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 975.100375] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 975.204564] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 975.319609] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 975.454099] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 975.570354] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 975.734463] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 975.878551] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 976.009822] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 976.144100] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 985.592545] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 02:03:17 (1772434997) [ 986.251957] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 986.322583] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 986.423913] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 986.531514] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 986.771765] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 986.880711] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 986.991301] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 987.061851] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 987.148559] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 987.242619] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 987.372630] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 987.469042] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 987.779920] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 987.914182] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 987.968491] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 988.050073] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 988.146907] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 988.216626] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 988.298247] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 988.467835] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 988.543926] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 988.623661] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 988.741955] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 988.858143] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 989.278916] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 989.332116] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 989.434861] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 989.486753] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 989.683900] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 989.807577] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 989.908800] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 990.043494] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 990.168716] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 990.461622] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 990.637644] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 990.803865] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 990.994256] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 991.136953] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 991.281279] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 991.457626] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 991.587764] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 991.693355] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 991.786143] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 991.964750] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 992.069604] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 992.229260] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 992.493243] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 992.595062] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 992.764952] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 992.849075] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 993.007060] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 993.099244] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 993.303580] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 993.379639] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 993.684457] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 993.759777] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 993.861411] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 993.936517] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 994.019700] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 994.106613] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 994.205378] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 994.352231] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 994.417892] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 994.515542] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 994.589276] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 994.680244] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 994.977712] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 995.139914] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 995.316674] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 995.437838] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 995.556296] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 995.627417] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 995.742570] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 995.816563] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 995.889434] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 995.997170] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 996.170265] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 996.335841] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 996.459960] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 996.554094] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 996.636306] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 996.732906] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 996.851970] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 996.989333] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 997.166600] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 997.240864] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 997.316704] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 997.398771] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 997.504759] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 997.697570] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 997.831714] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 998.009188] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 998.127563] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 998.216507] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 998.271366] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 998.355353] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 998.558358] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 998.848664] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 998.880417] Lustre: lustre-OST0000-osc-ffff8c9e47216800: disconnect after 21s idle [ 998.891627] Lustre: Skipped 1 previous similar message [ 998.986885] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 999.091895] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 999.251989] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 999.309758] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 999.526869] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 999.622788] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 999.849150] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1000.016770] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1000.097948] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1000.264848] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1000.508844] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1000.655345] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1000.820527] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1000.972810] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1001.129167] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1001.246582] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1001.331077] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1001.514705] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1001.586949] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1001.655662] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1001.933607] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.005973] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.086522] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.175829] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.224885] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.379542] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.447197] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.519474] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.628425] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.678883] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.753247] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.805962] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1002.981963] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1003.148662] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1003.274413] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1003.412930] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1003.549495] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1003.615788] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1003.714307] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1003.857354] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1003.902757] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1004.051641] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1004.092581] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1004.199072] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1004.308527] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1004.368488] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1004.503836] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1004.682751] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1004.795178] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1004.864506] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1005.075994] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1005.200760] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1005.279201] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1005.495113] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1005.570989] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1005.699593] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1005.775566] rw_seq_cst_vs_d (30067): drop_caches: 3 [ 1014.716342] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 02:03:46 (1772435026) [ 1023.875226] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 02:03:55 (1772435035) [ 1033.237490] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 02:04:04 (1772435044) [ 1084.003772] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 02:04:55 (1772435095) [ 1084.492898] bash (32509): drop_caches: 3 [ 1087.856430] bash (32509): drop_caches: 3 [ 1091.098696] bash (32509): drop_caches: 3 [ 1095.161146] bash (32509): drop_caches: 3 [ 1098.705990] bash (32509): drop_caches: 3 [ 1101.911212] bash (32509): drop_caches: 3 [ 1105.091350] bash (32509): drop_caches: 3 [ 1108.324173] bash (32509): drop_caches: 3 [ 1111.505459] bash (32509): drop_caches: 3 [ 1114.725669] bash (32509): drop_caches: 3 [ 1117.882670] bash (32509): drop_caches: 3 [ 1121.070273] bash (32509): drop_caches: 3 [ 1124.225476] bash (32509): drop_caches: 3 [ 1127.377991] bash (32509): drop_caches: 3 [ 1130.569566] bash (32509): drop_caches: 3 [ 1133.745336] bash (32509): drop_caches: 3 [ 1136.952749] bash (32509): drop_caches: 3 [ 1140.130714] bash (32509): drop_caches: 3 [ 1143.329272] bash (32509): drop_caches: 3 [ 1146.604140] bash (32509): drop_caches: 3 [ 1149.884642] bash (32509): drop_caches: 3 [ 1155.744115] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 02:06:06 (1772435166) [ 1168.593717] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 02:06:20 (1772435180) [ 1200.217289] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 02:06:51 (1772435211) [ 1203.197864] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 1203.691546] Lustre: lustre-OST0000-osc-ffff8c9e49fec800: disconnect after 20s idle [ 1203.710888] Lustre: Skipped 1 previous similar message [ 1205.271215] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 02:06:56 (1772435216) [ 1213.891416] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 02:07:05 (1772435225) [ 1222.367303] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 02:07:13 (1772435233) [ 1294.397419] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 02:08:25 (1772435305) [ 1303.726527] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 02:08:34 (1772435314) [ 1310.508327] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 02:08:42 (1772435322) [ 1318.514777] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 02:08:50 (1772435330) [ 1319.950945] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1321.667332] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 02:08:53 (1772435333) [ 1326.560547] Lustre: lustre-OST0001-osc-ffff8c9e47216800: disconnect after 22s idle [ 1326.568038] Lustre: Skipped 5 previous similar messages [ 1330.120279] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 02:09:01 (1772435341) [ 1339.473980] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 02:09:11 (1772435351) [ 1346.955966] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1348.961291] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 02:09:20 (1772435360) [ 1357.317688] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 02:09:29 (1772435369) [ 1357.607781] Lustre: *** cfs_fail_loc=314, val=0*** [ 1358.689745] Lustre: *** cfs_fail_loc=314, val=0*** [ 1358.691633] Lustre: Skipped 2 previous similar messages [ 1364.467796] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 02:09:36 (1772435376) [ 1379.697494] Lustre: *** cfs_fail_loc=314, val=0*** [ 1379.826923] LustreError: lustre-OST0000-osc-ffff8c9e49fec800: operation ldlm_enqueue to node 192.168.201.144@tcp failed: rc = -107 [ 1379.836300] Lustre: lustre-OST0000-osc-ffff8c9e49fec800: Connection to lustre-OST0000 (at 192.168.201.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1379.851438] LustreError: lustre-OST0000-osc-ffff8c9e49fec800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1379.872714] Lustre: 2347:0:(llite_lib.c:4187:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.144@tcp:/lustre/fid: [0x200000402:0x24:0x0]// may get corrupted (rc -108) [ 1379.884644] LustreError: 41770:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff8c9e49fec800: namespace resource [0x240000400:0x35:0x0].0x0 (ffff8c9e4ba25700) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1379.897131] Lustre: lustre-OST0000-osc-ffff8c9e49fec800: Connection restored to 192.168.201.144@tcp (at 192.168.201.144@tcp) [ 1386.835781] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 02:09:58 (1772435398) [ 1387.050102] LustreError: 42352:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1390.090450] LustreError: 42352:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1397.322947] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 02:10:08 (1772435408) [ 1406.570487] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 02:10:17 (1772435417) [ 1414.546942] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1415.932087] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 02:10:28 (1772435428) [ 1417.918501] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1419.634409] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 02:10:31 (1772435431) [ 1421.273824] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1424.060207] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 02:10:35 (1772435435) [ 1425.904289] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1427.733961] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 02:10:39 (1772435439) [ 1429.499610] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1431.307356] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 02:10:43 (1772435443) [ 1487.292433] Lustre: lustre-OST0001-osc-ffff8c9e47216800: Connection to lustre-OST0001 (at 192.168.201.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1487.317712] LustreError: lustre-OST0001-osc-ffff8c9e47216800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1487.354430] Lustre: lustre-OST0001-osc-ffff8c9e47216800: Connection restored to 192.168.201.144@tcp (at 192.168.201.144@tcp) [ 1487.372612] LustreError: lustre-OST0001-osc-ffff8c9e49fec800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1508.832293] Lustre: lustre-OST0001-osc-ffff8c9e47216800: disconnect after 22s idle [ 1508.841257] Lustre: Skipped 2 previous similar messages [ 1508.853455] Lustre: lustre-OST0000-osc-ffff8c9e47216800: Connection to lustre-OST0000 (at 192.168.201.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1508.867776] Lustre: Skipped 1 previous similar message [ 1508.875841] LustreError: lustre-OST0000-osc-ffff8c9e47216800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1508.888435] Lustre: lustre-OST0000-osc-ffff8c9e47216800: Connection restored to 192.168.201.144@tcp (at 192.168.201.144@tcp) [ 1508.897828] Lustre: Skipped 1 previous similar message [ 1523.777891] Lustre: DEBUG MARKER: oleg144-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8c9e47216800.ost_server_uuid 50 [ 1525.723590] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8c9e47216800.ost_server_uuid in FULL state after 0 sec [ 1530.041193] Lustre: DEBUG MARKER: oleg144-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8c9e47216800.ost_server_uuid 50 [ 1531.489685] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8c9e47216800.ost_server_uuid in IDLE state after 0 sec [ 1537.632779] Lustre: DEBUG MARKER: oleg144-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8c9e47216800.ost_server_uuid 50 [ 1539.363357] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8c9e47216800.ost_server_uuid in IDLE state after 0 sec [ 1544.179900] Lustre: DEBUG MARKER: oleg144-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8c9e47216800.ost_server_uuid 50 [ 1546.433858] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8c9e47216800.ost_server_uuid in IDLE state after 0 sec [ 1558.851995] Lustre: DEBUG MARKER: oleg144-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8c9e47216800.ost_server_uuid 50 [ 1560.838525] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8c9e47216800.ost_server_uuid in IDLE state after 0 sec [ 1564.971606] Lustre: DEBUG MARKER: oleg144-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8c9e47216800.ost_server_uuid 50 [ 1566.696736] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8c9e47216800.ost_server_uuid in IDLE state after 0 sec [ 1568.867979] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 02:13:00 (1772435580) [ 1572.038968] Lustre: DEBUG MARKER: Race attempt 0 [ 1575.478744] Lustre: DEBUG MARKER: Wait for 48993 49008 for 60 sec... [ 1643.082757] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 02:14:14 (1772435654) [ 1652.783339] Lustre: DEBUG MARKER: start test - cycle (0) [ 1690.106300] Lustre: DEBUG MARKER: start test - cycle (1) [ 1720.175306] Lustre: DEBUG MARKER: start test - cycle (2) [ 1749.674229] Lustre: DEBUG MARKER: start test - cycle (3) [ 1779.781901] Lustre: DEBUG MARKER: start test - cycle (4) [ 1780.197555] Lustre: lustre-OST0001-osc-ffff8c9e49fec800: disconnect after 21s idle [ 1780.204432] Lustre: Skipped 9 previous similar messages [ 1809.429774] Lustre: DEBUG MARKER: start test - cycle (5) [ 1835.887612] Lustre: DEBUG MARKER: start test - cycle (6) [ 1862.199794] Lustre: DEBUG MARKER: start test - cycle (7) [ 1888.094265] Lustre: DEBUG MARKER: start test - cycle (8) [ 1905.862801] Lustre: DEBUG MARKER: start test - cycle (9) [ 1933.659430] Lustre: DEBUG MARKER: start test - cycle (10) [ 1958.401587] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 02:19:30 (1772435970) [ 2037.630059] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 02:20:49 (1772436049) [ 2044.467583] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 02:20:56 (1772436056) [ 2051.598443] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 02:21:03 (1772436063) [ 2058.622847] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 02:21:10 (1772436070) [ 2058.859424] Lustre: *** cfs_fail_loc=411, val=0*** [ 2064.242576] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2065.830991] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 02:21:18 (1772436078) [ 2083.034674] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 02:21:35 (1772436095) [ 2100.590501] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 02:21:52 (1772436112) [ 2114.198750] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 02:22:06 (1772436126) [ 2127.726517] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 02:22:19 (1772436139) [ 2139.297690] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 02:22:31 (1772436151) [ 2150.226370] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 02:22:42 (1772436162) [ 2160.761319] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 02:22:53 (1772436173) [ 2172.088826] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 02:23:04 (1772436184) [ 2182.895751] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 02:23:15 (1772436195) [ 2192.203532] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 02:23:24 (1772436204) [ 2201.545259] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 02:23:33 (1772436213) [ 2212.021422] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 02:23:44 (1772436224) [ 2829.792243] Lustre: lustre-OST0000-osc-ffff8c9e49fec800: disconnect after 22s idle [ 2829.797825] Lustre: Skipped 16 previous similar messages [ 3121.442826] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 02:38:53 (1772437133) [ 3130.324764] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 02:39:02 (1772437142) [ 3138.440909] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 02:39:10 (1772437150) [ 3147.039168] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 02:39:19 (1772437159) [ 3155.308047] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 02:39:27 (1772437167) [ 3167.104877] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 02:39:39 (1772437179) [ 3176.660294] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 02:39:49 (1772437189) [ 3186.192431] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 02:39:58 (1772437198) [ 3195.474690] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 02:40:07 (1772437207) [ 3238.553759] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 02:40:51 (1772437251) [ 3247.626528] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 02:40:59 (1772437259) [ 3257.314713] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 02:41:09 (1772437269) [ 3267.083476] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 02:41:19 (1772437279) [ 3276.427976] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 02:41:28 (1772437288) [ 3286.331315] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 02:41:38 (1772437298) [ 3295.901585] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 02:41:48 (1772437308) [ 3305.566175] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 02:41:58 (1772437318) [ 3306.535046] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 3307.523295] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 02:42:00 (1772437320) [ 3393.599561] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 02:43:25 (1772437405) [ 3439.072256] Lustre: lustre-OST0000-osc-ffff8c9e47216800: disconnect after 21s idle [ 3439.076580] Lustre: Skipped 6 previous similar messages [ 3991.082669] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 02:53:23 (1772438003) [ 3997.330499] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 02:53:30 (1772438010) [ 4004.293702] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 02:53:37 (1772438017) [ 4010.717634] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 02:53:43 (1772438023) [ 4017.199856] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 02:53:49 (1772438029) [ 4023.391573] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 02:53:56 (1772438036) [ 4029.429663] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 02:54:02 (1772438042) [ 4035.167285] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 02:54:08 (1772438048) [ 4041.462466] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 02:54:14 (1772438054) [ 4042.117218] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 4042.919685] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 02:54:15 (1772438055) [ 4058.592159] Lustre: lustre-OST0000-osc-ffff8c9e47216800: disconnect after 22s idle [ 4058.594441] Lustre: Skipped 5 previous similar messages [ 4096.792501] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 02:55:09 (1772438109) [ 4103.798922] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 02:55:16 (1772438116) [ 4110.533840] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 02:55:23 (1772438123) [ 4117.390139] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 02:55:30 (1772438130) [ 4123.936395] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 02:55:36 (1772438136) [ 4130.159102] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 02:55:42 (1772438142) [ 4136.532886] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 02:55:49 (1772438149) [ 4142.353099] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 02:55:55 (1772438155) [ 4143.019931] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 4143.758480] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 02:55:56 (1772438156) [ 4621.664393] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 03:03:54 (1772438634) [ 4627.143386] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 03:04:00 (1772438640) [ 4632.661895] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 03:04:05 (1772438645) [ 4637.985219] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 03:04:10 (1772438650) [ 4643.395707] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 03:04:16 (1772438656) [ 4648.892813] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 03:04:21 (1772438661) [ 4654.501573] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 03:04:27 (1772438667) [ 4659.914561] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 03:04:32 (1772438672) [ 4665.337731] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 03:04:38 (1772438678) [ 4665.872269] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 4666.464863] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 03:04:39 (1772438679) [ 4666.999918] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 4667.626703] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 03:04:40 (1772438680) [ 4668.163575] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 4668.712598] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 03:04:41 (1772438681) [ 4669.238870] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 4669.796124] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 03:04:42 (1772438682) [ 4670.278655] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 4670.841152] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 03:04:43 (1772438683) [ 4671.355812] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 4671.922065] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 03:04:44 (1772438684) [ 4672.434960] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 4673.013676] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 03:04:45 (1772438685) [ 4673.529599] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 4674.125498] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 03:04:47 (1772438687) [ 4674.199690] LustreError: 5530:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4676.280119] LustreError: 5530:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4681.400980] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 03:04:54 (1772438694) [ 4685.773210] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 03:04:58 (1772438698) [ 4685.863201] LustreError: 216830:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4689.920155] LustreError: 216830:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4689.929421] LustreError: 216830:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4693.992148] LustreError: 216830:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4694.009684] LustreError: 216836:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4698.072146] LustreError: 216836:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4700.454154] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 03:05:13 (1772438713) [ 4707.107908] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 03:05:20 (1772438720) [ 4710.383907] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 03:05:23 (1772438723) [ 4714.576989] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 03:05:27 (1772438727) [ 4724.192233] Lustre: lustre-OST0000-osc-ffff8c9e47216800: disconnect after 21s idle [ 4724.194611] Lustre: Skipped 2 previous similar messages [ 4738.552218] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 03:05:51 (1772438751) [ 4745.942931] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 03:05:58 (1772438758) [ 4753.431100] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 03:06:06 (1772438766) [ 4766.086524] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 03:06:19 (1772438779) [ 4775.478230] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 03:06:28 (1772438788) [ 4775.921842] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 4776.483655] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 03:06:29 (1772438789) [ 4789.253461] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 03:06:42 (1772438802) [ 4803.325230] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 03:06:56 (1772438816) [ 4830.349623] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 03:07:23 (1772438843) [ 4870.532900] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 03:08:03 (1772438883) [ 4873.072531] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4875.849698] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 03:08:08 (1772438888) [ 4879.039167] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 03:08:11 (1772438891) [ 4879.649312] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4880.262431] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 03:08:13 (1772438893) [ 4880.931259] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4881.542094] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 03:08:14 (1772438894) [ 4882.074777] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 4882.720727] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 03:08:15 (1772438895) [ 4883.326956] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4883.904138] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 03:08:16 (1772438896) [ 4886.183880] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 03:08:19 (1772438899) [ 4888.503513] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 03:08:21 (1772438901) [ 4891.582609] LustreError: lustre-MDT0000-mdc-ffff8c9e47216800: operation ldlm_enqueue to node 192.168.201.144@tcp failed: rc = -35 [ 4894.576781] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 03:08:27 (1772438907) [ 4894.705719] LustreError: 2345:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4896.792106] LustreError: 2345:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 4901.779873] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 03:08:34 (1772438914) [ 4921.751789] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 03:08:54 (1772438934) [ 4924.799193] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 03:08:57 (1772438937) [ 4928.929113] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 03:09:01 (1772438941) [ 4933.995331] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 03:09:06 (1772438946) [ 4939.055469] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 03:09:11 (1772438951) [ 4946.862902] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 03:09:19 (1772438959) [ 4955.215125] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 03:09:28 (1772438968) [ 4959.110800] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 03:09:31 (1772438971) [ 4963.195304] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 03:09:36 (1772438976) [ 4970.378433] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 03:09:43 (1772438983) [ 5018.869528] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 03:10:31 (1772439031) [ 5157.191338] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 03:12:50 (1772439170) [ 5294.803312] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 03:15:07 (1772439307) [ 5359.072256] Lustre: lustre-OST0001-osc-ffff8c9e47216800: disconnect after 21s idle [ 5359.074965] Lustre: Skipped 12 previous similar messages [ 5649.570891] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 03:21:02 (1772439662) [ 5686.746302] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 03:21:39 (1772439699) [ 5727.841065] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 03:22:20 (1772439740) [ 5763.469779] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 03:22:56 (1772439776) [ 5840.294923] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 03:24:13 (1772439853) [ 5906.041270] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 03:25:18 (1772439918) [ 5968.352179] Lustre: lustre-OST0001-osc-ffff8c9e47216800: disconnect after 24s idle [ 5968.354340] Lustre: Skipped 15 previous similar messages [ 6024.213546] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 03:27:17 (1772440037) [ 6025.227740] LustreError: 258835:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e47216800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6025.241497] Lustre: Unmounted lustre-client [ 6026.167729] LustreError: 258848:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e49fec800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6026.170877] LustreError: 258848:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6026.182297] Lustre: Unmounted lustre-client [ 6069.844743] Lustre: Mounted lustre-client [ 6071.377571] Lustre: Mounted lustre-client [ 6072.363630] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6159.188992] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 03:29:32 (1772440172) [ 6166.955186] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 03:29:39 (1772440179) [ 6170.346747] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 03:29:43 (1772440183) [ 6221.059355] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 03:30:33 (1772440233) [ 6286.480910] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 03:31:39 (1772440299) [ 6290.257897] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 03:31:43 (1772440303) [ 6328.248257] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 03:32:21 (1772440341) [ 6339.591360] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 03:32:32 (1772440352) [ 6381.296428] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 03:33:14 (1772440394) [ 6384.384170] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 03:33:17 (1772440397) [ 6397.065330] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 03:33:29 (1772440409) [ 6397.579204] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 6398.162303] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 03:33:31 (1772440411) [ 6398.730121] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 6399.279125] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 03:33:32 (1772440412) [ 6399.757399] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 6400.289380] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 03:33:33 (1772440413) [ 6400.790509] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 6401.334921] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 03:33:34 (1772440414) [ 6401.828849] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6402.376161] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 03:33:35 (1772440415) [ 6426.738318] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 03:33:59 (1772440439) [ 6428.997847] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 03:34:01 (1772440441) [ 6429.479698] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 6430.002616] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 03:34:02 (1772440442) [ 6437.360194] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 03:34:10 (1772440450) [ 6439.951610] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 03:34:12 (1772440452) [ 6440.426828] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 6440.939078] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 03:34:13 (1772440453) [ 6441.410184] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 6441.903070] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 03:34:14 (1772440454) [ 6442.382609] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 6442.900490] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 03:34:15 (1772440455) [ 6451.761981] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 03:34:24 (1772440464) [ 6451.853846] Lustre: DEBUG MARKER: write [ 6451.868920] LustreError: 260249:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6453.875230] Lustre: DEBUG MARKER: kill 288630 [ 6453.877613] LustreError: 288630:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6456.968108] LustreError: 260249:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 6459.912110] LustreError: 288630:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 6462.078630] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 03:34:35 (1772440475) [ 6464.247861] LustreError: 289236:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 6474.344078] LustreError: 289236:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 awake [ 6476.480223] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 03:34:49 (1772440489) [ 6476.582663] LustreError: 289815:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6478.664150] LustreError: 289815:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout interrupted [ 6484.784546] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 03:34:57 (1772440497) [ 6485.273831] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6485.804447] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 03:34:58 (1772440498) [ 6488.147220] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 03:35:01 (1772440501) [ 6490.339993] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 03:35:03 (1772440503) [ 6492.547557] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 03:35:05 (1772440505) [ 6494.780707] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 03:35:07 (1772440507) [ 6496.976884] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 03:35:09 (1772440509) [ 6499.241279] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 03:35:12 (1772440512) [ 6502.497849] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 6503.057032] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 03:35:15 (1772440515) [ 6503.666834] Lustre: *** cfs_fail_loc=415, val=0*** [ 6510.479225] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 03:35:23 (1772440523) [ 6511.006260] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 6511.570713] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 03:35:24 (1772440524) [ 6511.653827] LustreError: 265088:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6511.656391] LustreError: 265088:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 6516.752109] LustreError: 260249:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6516.755571] LustreError: 260249:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 6526.944154] LustreError: 260771:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6526.948168] LustreError: 260771:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 6534.348530] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 03:35:47 (1772440547) [ 6534.859157] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 6535.424738] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 03:35:48 (1772440548) [ 6537.846641] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 03:35:50 (1772440550) [ 6540.002544] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 03:35:52 (1772440552) [ 6544.171600] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 03:35:57 (1772440557) [ 6552.469190] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 03:36:05 (1772440565) [ 6552.617047] LustreError: 292733:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6552.619325] LustreError: 292733:0:(osc_request.c:2968:osc_build_rpc()) Skipped 5 previous similar messages [ 6556.680113] LustreError: 292733:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6556.683222] LustreError: 292733:0:(osc_request.c:2968:osc_build_rpc()) Skipped 2 previous similar messages [ 6558.836375] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 03:36:11 (1772440571) [ 6559.567924] LustreError: 299756:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e4399f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6559.571225] LustreError: 299756:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6559.586100] Lustre: Unmounted lustre-client [ 6560.321916] Lustre: DEBUG MARKER: Iteration 1 [ 6560.412666] LustreError: 299935:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6560.412698] LustreError: 299937:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6560.417759] LustreError: 299935:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6560.462923] Lustre: Mounted lustre-client [ 6561.952418] Key type lgssc unregistered [ 6562.081345] LNet: 300281:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6562.083786] LNetError: 300281:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6562.093928] LNet: Removed LNI 192.168.201.44@tcp [ 6562.402108] Key type .llcrypt unregistered [ 6562.403047] Key type ._llcrypt unregistered [ 6562.649670] Key type ._llcrypt registered [ 6562.650715] Key type .llcrypt registered [ 6562.976130] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6562.981094] alg: No test for adler32 (adler32-zlib) [ 6563.970410] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6564.245612] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6565.864132] Key type lgssc registered [ 6566.394115] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6570.717790] Lustre: DEBUG MARKER: Iteration 2 [ 6570.824724] LustreError: 301103:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6570.824752] LustreError: 301104:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6570.829341] LustreError: 301103:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6571.643289] Lustre: Mounted lustre-client [ 6571.644721] Lustre: Skipped 1 previous similar message [ 6572.113927] LustreError: 301210:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e43999800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6572.118218] LustreError: 301210:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6572.162576] Lustre: Unmounted lustre-client [ 6572.163618] Lustre: Skipped 1 previous similar message [ 6573.181891] Key type lgssc unregistered [ 6573.306778] LNet: 301453:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6573.310083] LNetError: 301453:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6573.319996] LNet: Removed LNI 192.168.201.44@tcp [ 6573.591102] Key type .llcrypt unregistered [ 6573.592815] Key type ._llcrypt unregistered [ 6573.908688] Key type ._llcrypt registered [ 6573.910261] Key type .llcrypt registered [ 6574.171102] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6574.176423] alg: No test for adler32 (adler32-zlib) [ 6575.049176] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6575.136256] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6576.720233] Key type lgssc registered [ 6577.066160] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6580.753299] Lustre: DEBUG MARKER: Iteration 3 [ 6580.886212] LustreError: 302273:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6580.887310] LustreError: 302274:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6580.890921] LustreError: 302273:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6581.697316] Lustre: Mounted lustre-client [ 6581.698737] Lustre: Skipped 1 previous similar message [ 6582.180878] LustreError: 302380:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e4652e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6582.185891] LustreError: 302380:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6582.213116] Lustre: Unmounted lustre-client [ 6583.160921] Key type lgssc unregistered [ 6583.272606] LNet: 302622:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6583.277155] LNetError: 302622:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6583.285778] LNet: Removed LNI 192.168.201.44@tcp [ 6583.536111] Key type .llcrypt unregistered [ 6583.537121] Key type ._llcrypt unregistered [ 6583.885297] Key type ._llcrypt registered [ 6583.886367] Key type .llcrypt registered [ 6584.096405] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6584.101649] alg: No test for adler32 (adler32-zlib) [ 6584.978923] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6585.069752] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6586.656143] Key type lgssc registered [ 6587.081411] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6590.740782] Lustre: DEBUG MARKER: Iteration 4 [ 6590.843994] LustreError: 303445:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6590.844053] LustreError: 303444:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6590.848872] LustreError: 303445:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6591.651891] Lustre: Mounted lustre-client [ 6591.653276] Lustre: Skipped 1 previous similar message [ 6592.092390] LustreError: 303549:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e5819f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6592.132561] Lustre: Unmounted lustre-client [ 6593.066283] Key type lgssc unregistered [ 6593.190584] LNet: 303792:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6593.194667] LNetError: 303792:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6593.202723] LNet: Removed LNI 192.168.201.44@tcp [ 6593.457144] Key type .llcrypt unregistered [ 6593.458239] Key type ._llcrypt unregistered [ 6593.779996] Key type ._llcrypt registered [ 6593.782277] Key type .llcrypt registered [ 6594.022071] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6594.029284] alg: No test for adler32 (adler32-zlib) [ 6594.895770] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6594.975375] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6596.568126] Key type lgssc registered [ 6597.038527] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6601.059949] Lustre: DEBUG MARKER: Iteration 5 [ 6601.161770] LustreError: 304613:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6601.161815] LustreError: 304612:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6601.167856] LustreError: 304613:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6601.974167] Lustre: Mounted lustre-client [ 6601.975421] Lustre: Skipped 1 previous similar message [ 6602.458783] LustreError: 304715:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e48ee5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6602.462175] LustreError: 304715:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6602.517100] Lustre: Unmounted lustre-client [ 6603.508183] Key type lgssc unregistered [ 6603.624525] LNet: 304957:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6603.628281] LNetError: 304957:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6603.640546] LNet: Removed LNI 192.168.201.44@tcp [ 6603.899156] Key type .llcrypt unregistered [ 6603.900088] Key type ._llcrypt unregistered [ 6604.268221] Key type ._llcrypt registered [ 6604.269838] Key type .llcrypt registered [ 6604.522979] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6604.530120] alg: No test for adler32 (adler32-zlib) [ 6605.414354] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6605.525519] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6607.120141] Key type lgssc registered [ 6607.576164] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6611.217448] Lustre: DEBUG MARKER: Iteration 6 [ 6611.342064] LustreError: 305778:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6611.345244] LustreError: 305779:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6611.348575] LustreError: 305778:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6612.155208] Lustre: Mounted lustre-client [ 6612.156590] Lustre: Skipped 1 previous similar message [ 6612.759736] LustreError: 305884:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e45659000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6612.811370] Lustre: Unmounted lustre-client [ 6613.888278] Key type lgssc unregistered [ 6614.027623] LNet: 306127:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6614.030064] LNetError: 306127:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6614.038217] LNet: Removed LNI 192.168.201.44@tcp [ 6614.316132] Key type .llcrypt unregistered [ 6614.317554] Key type ._llcrypt unregistered [ 6614.621358] Key type ._llcrypt registered [ 6614.622347] Key type .llcrypt registered [ 6614.822185] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6614.828447] alg: No test for adler32 (adler32-zlib) [ 6615.703292] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6615.804375] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6617.392192] Key type lgssc registered [ 6617.802583] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6621.369309] Lustre: DEBUG MARKER: Iteration 7 [ 6621.498364] LustreError: 306947:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6621.499544] LustreError: 306948:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6621.504584] LustreError: 306947:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6622.306169] Lustre: Mounted lustre-client [ 6622.308204] Lustre: Skipped 1 previous similar message [ 6622.818446] LustreError: 307054:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e495ce000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6622.822938] LustreError: 307054:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6622.851088] Lustre: Unmounted lustre-client [ 6623.897909] Key type lgssc unregistered [ 6624.017698] LNet: 307298:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6624.020823] LNetError: 307298:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6624.031731] LNet: Removed LNI 192.168.201.44@tcp [ 6624.297137] Key type .llcrypt unregistered [ 6624.298756] Key type ._llcrypt unregistered [ 6624.632707] Key type ._llcrypt registered [ 6624.633895] Key type .llcrypt registered [ 6624.814514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6624.821431] alg: No test for adler32 (adler32-zlib) [ 6625.682813] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6625.771315] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6627.360177] Key type lgssc registered [ 6627.784357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6631.628519] Lustre: DEBUG MARKER: Iteration 8 [ 6631.756657] LustreError: 308119:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6631.757843] LustreError: 308120:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6631.763242] LustreError: 308119:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6632.571290] Lustre: Mounted lustre-client [ 6632.572632] Lustre: Skipped 1 previous similar message [ 6633.032027] LustreError: 308227:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e80b20000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6633.037608] LustreError: 308227:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6633.051068] Lustre: Unmounted lustre-client [ 6634.084913] Key type lgssc unregistered [ 6634.205473] LNet: 308470:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6634.207710] LNetError: 308470:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6634.217344] LNet: Removed LNI 192.168.201.44@tcp [ 6634.489131] Key type .llcrypt unregistered [ 6634.490800] Key type ._llcrypt unregistered [ 6634.771428] Key type ._llcrypt registered [ 6634.773390] Key type .llcrypt registered [ 6634.988833] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6634.995136] alg: No test for adler32 (adler32-zlib) [ 6635.864128] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6635.960980] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6637.552178] Key type lgssc registered [ 6637.963605] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6641.914200] Lustre: DEBUG MARKER: Iteration 9 [ 6642.030311] LustreError: 309292:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6642.030337] LustreError: 309291:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6642.037312] LustreError: 309292:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6642.839339] Lustre: Mounted lustre-client [ 6642.841193] Lustre: Skipped 1 previous similar message [ 6643.331417] LustreError: 309393:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e4565b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6643.336546] LustreError: 309393:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6643.352774] Lustre: Unmounted lustre-client [ 6644.371803] Key type lgssc unregistered [ 6644.491546] LNet: 309636:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6644.493618] LNetError: 309636:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6644.503722] LNet: Removed LNI 192.168.201.44@tcp [ 6644.751093] Key type .llcrypt unregistered [ 6644.752363] Key type ._llcrypt unregistered [ 6645.118750] Key type ._llcrypt registered [ 6645.119756] Key type .llcrypt registered [ 6645.284494] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6645.289945] alg: No test for adler32 (adler32-zlib) [ 6646.161083] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6646.242721] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6647.824186] Key type lgssc registered [ 6648.203924] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6651.674518] Lustre: DEBUG MARKER: Iteration 10 [ 6651.795379] LustreError: 310460:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6651.795427] LustreError: 310459:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6651.799643] LustreError: 310460:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6652.596339] Lustre: Mounted lustre-client [ 6653.139618] LustreError: 310566:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e80016000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6653.143566] LustreError: 310566:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6653.167183] Lustre: Unmounted lustre-client [ 6654.215410] Key type lgssc unregistered [ 6654.336689] LNet: 310809:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6654.339312] LNetError: 310809:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6654.346475] LNet: Removed LNI 192.168.201.44@tcp [ 6654.597131] Key type .llcrypt unregistered [ 6654.598202] Key type ._llcrypt unregistered [ 6655.035546] Key type ._llcrypt registered [ 6655.040449] Key type .llcrypt registered [ 6655.198851] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6655.204412] alg: No test for adler32 (adler32-zlib) [ 6656.074881] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6656.167341] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6657.752169] Key type lgssc registered [ 6658.185106] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6661.955281] Lustre: DEBUG MARKER: Iteration 11 [ 6662.081130] LustreError: 311630:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6662.081386] LustreError: 311631:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6662.085591] LustreError: 311630:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6662.894907] Lustre: Mounted lustre-client [ 6663.455266] LustreError: 311738:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e456e4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6663.515223] Lustre: Unmounted lustre-client [ 6664.620182] Key type lgssc unregistered [ 6664.745750] LNet: 311982:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6664.749595] LNetError: 311982:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6664.759529] LNet: Removed LNI 192.168.201.44@tcp [ 6665.032119] Key type .llcrypt unregistered [ 6665.033758] Key type ._llcrypt unregistered [ 6665.461657] Key type ._llcrypt registered [ 6665.463289] Key type .llcrypt registered [ 6665.647310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6665.652841] alg: No test for adler32 (adler32-zlib) [ 6666.517755] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6666.613407] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6668.200120] Key type lgssc registered [ 6668.593343] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6672.198365] Lustre: DEBUG MARKER: Iteration 12 [ 6672.295331] LustreError: 312803:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6672.296498] LustreError: 312804:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6672.299710] LustreError: 312803:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6673.092398] Lustre: Mounted lustre-client [ 6673.524140] LustreError: 312906:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e5819a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6673.528057] LustreError: 312906:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6673.555097] Lustre: Unmounted lustre-client [ 6674.502642] Key type lgssc unregistered [ 6674.614264] LNet: 313149:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6674.616605] LNetError: 313149:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6674.625621] LNet: Removed LNI 192.168.201.44@tcp [ 6674.841118] Key type .llcrypt unregistered [ 6674.842818] Key type ._llcrypt unregistered [ 6675.098910] Key type ._llcrypt registered [ 6675.099931] Key type .llcrypt registered [ 6675.265703] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6675.271166] alg: No test for adler32 (adler32-zlib) [ 6676.125966] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6676.208659] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6677.792180] Key type lgssc registered [ 6678.187290] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6681.874852] Lustre: DEBUG MARKER: Iteration 13 [ 6681.987542] LustreError: 313971:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6681.987575] LustreError: 313970:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6681.993429] LustreError: 313971:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6682.784835] Lustre: Mounted lustre-client [ 6682.786820] Lustre: Skipped 1 previous similar message [ 6683.230146] LustreError: 314072:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e44f18800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6683.234105] LustreError: 314072:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6683.283102] Lustre: Unmounted lustre-client [ 6684.211731] Key type lgssc unregistered [ 6684.333545] LNet: 314317:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6684.336701] LNetError: 314317:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6684.345683] LNet: Removed LNI 192.168.201.44@tcp [ 6684.612118] Key type .llcrypt unregistered [ 6684.613689] Key type ._llcrypt unregistered [ 6684.913464] Key type ._llcrypt registered [ 6684.915578] Key type .llcrypt registered [ 6685.199788] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6685.205666] alg: No test for adler32 (adler32-zlib) [ 6686.088120] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6686.190018] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6687.776217] Key type lgssc registered [ 6688.222037] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6691.859382] Lustre: DEBUG MARKER: Iteration 14 [ 6691.984720] LustreError: 315137:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6691.984975] LustreError: 315139:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6691.989578] LustreError: 315137:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6692.802453] Lustre: Mounted lustre-client [ 6692.803421] Lustre: Skipped 1 previous similar message [ 6693.363380] LustreError: 315240:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e455fc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6693.366927] LustreError: 315240:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6693.393091] Lustre: Unmounted lustre-client [ 6694.365185] Key type lgssc unregistered [ 6694.480383] LNet: 315482:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6694.483243] LNetError: 315482:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6694.491338] LNet: Removed LNI 192.168.201.44@tcp [ 6694.735110] Key type .llcrypt unregistered [ 6694.736280] Key type ._llcrypt unregistered [ 6694.985782] Key type ._llcrypt registered [ 6694.986817] Key type .llcrypt registered [ 6695.233960] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6695.255599] alg: No test for adler32 (adler32-zlib) [ 6696.117477] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6696.207198] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6697.800237] Key type lgssc registered [ 6698.208318] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6701.782295] Lustre: DEBUG MARKER: Iteration 15 [ 6701.878972] LustreError: 316301:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6701.879102] LustreError: 316303:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6701.884768] LustreError: 316301:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6702.680266] Lustre: Mounted lustre-client [ 6703.121311] LustreError: 316405:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e455f9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6703.163175] Lustre: Unmounted lustre-client [ 6704.242234] Key type lgssc unregistered [ 6704.356788] LNet: 316648:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6704.359854] LNetError: 316648:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6704.373130] LNet: Removed LNI 192.168.201.44@tcp [ 6704.630102] Key type .llcrypt unregistered [ 6704.631204] Key type ._llcrypt unregistered [ 6705.008676] Key type ._llcrypt registered [ 6705.009882] Key type .llcrypt registered [ 6705.131856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6705.137203] alg: No test for adler32 (adler32-zlib) [ 6706.000996] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6706.089744] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6707.688144] Key type lgssc registered [ 6708.104686] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6711.896587] Lustre: DEBUG MARKER: Iteration 16 [ 6712.009074] LustreError: 317469:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6712.010326] LustreError: 317470:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6712.013519] LustreError: 317469:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6712.817870] Lustre: Mounted lustre-client [ 6713.357510] LustreError: 317578:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e4652e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6713.403902] Lustre: Unmounted lustre-client [ 6714.344401] Key type lgssc unregistered [ 6714.461690] LNet: 317819:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6714.465320] LNetError: 317819:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6714.475747] LNet: Removed LNI 192.168.201.44@tcp [ 6714.703126] Key type .llcrypt unregistered [ 6714.704145] Key type ._llcrypt unregistered [ 6714.952798] Key type ._llcrypt registered [ 6714.954141] Key type .llcrypt registered [ 6715.151532] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6715.157053] alg: No test for adler32 (adler32-zlib) [ 6716.023879] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6716.110308] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6717.696165] Key type lgssc registered [ 6718.090728] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6721.608659] Lustre: DEBUG MARKER: Iteration 17 [ 6721.710279] LustreError: 318640:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6721.710682] LustreError: 318641:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6721.717055] LustreError: 318640:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6722.523820] Lustre: Mounted lustre-client [ 6722.525514] Lustre: Skipped 1 previous similar message [ 6722.979531] LustreError: 318748:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e47078800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6722.983766] LustreError: 318748:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6722.999476] Lustre: Unmounted lustre-client [ 6723.994275] Key type lgssc unregistered [ 6724.126519] LNet: 318992:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6724.129457] LNetError: 318992:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6724.137605] LNet: Removed LNI 192.168.201.44@tcp [ 6724.400101] Key type .llcrypt unregistered [ 6724.401687] Key type ._llcrypt unregistered [ 6724.660571] Key type ._llcrypt registered [ 6724.662336] Key type .llcrypt registered [ 6724.886311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6724.892453] alg: No test for adler32 (adler32-zlib) [ 6725.778548] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6725.890186] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6727.488163] Key type lgssc registered [ 6727.929277] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6731.650195] Lustre: DEBUG MARKER: Iteration 18 [ 6731.777270] LustreError: 319812:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6731.777669] LustreError: 319813:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6731.782883] LustreError: 319812:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6732.582087] Lustre: Mounted lustre-client [ 6732.583387] Lustre: Skipped 1 previous similar message [ 6733.101348] LustreError: 319916:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e7b9c6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6733.105316] LustreError: 319916:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6733.140740] Lustre: Unmounted lustre-client [ 6734.059718] Key type lgssc unregistered [ 6734.168558] LNet: 320159:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6734.171332] LNetError: 320159:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6734.182809] LNet: Removed LNI 192.168.201.44@tcp [ 6734.432098] Key type .llcrypt unregistered [ 6734.433400] Key type ._llcrypt unregistered [ 6734.671798] Key type ._llcrypt registered [ 6734.673388] Key type .llcrypt registered [ 6734.897887] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6734.903565] alg: No test for adler32 (adler32-zlib) [ 6735.773587] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6735.865465] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6737.456136] Key type lgssc registered [ 6737.881944] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6741.227882] Lustre: DEBUG MARKER: Iteration 19 [ 6741.359351] LustreError: 320979:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6741.361096] LustreError: 320980:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6741.364314] LustreError: 320979:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6742.174481] Lustre: Mounted lustre-client [ 6742.716491] LustreError: 321087:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e4707b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6742.720306] LustreError: 321087:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6742.760215] Lustre: Unmounted lustre-client [ 6743.815947] Key type lgssc unregistered [ 6743.943722] LNet: 321330:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6743.946353] LNetError: 321330:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6743.955434] LNet: Removed LNI 192.168.201.44@tcp [ 6744.202096] Key type .llcrypt unregistered [ 6744.203172] Key type ._llcrypt unregistered [ 6744.491788] Key type ._llcrypt registered [ 6744.495774] Key type .llcrypt registered [ 6744.750461] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6744.755535] alg: No test for adler32 (adler32-zlib) [ 6745.630560] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6745.733111] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6747.328130] Key type lgssc registered [ 6747.755110] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6751.550507] Lustre: DEBUG MARKER: Iteration 20 [ 6751.649699] LustreError: 322151:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6751.649757] LustreError: 322153:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6751.655374] LustreError: 322151:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6752.453682] Lustre: Mounted lustre-client [ 6752.905258] LustreError: 322255:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e5879d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6752.908921] LustreError: 322255:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6752.931786] Lustre: Unmounted lustre-client [ 6753.787733] Key type lgssc unregistered [ 6753.898525] LNet: 322498:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6753.900690] LNetError: 322498:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6753.912694] LNet: Removed LNI 192.168.201.44@tcp [ 6754.142111] Key type .llcrypt unregistered [ 6754.143598] Key type ._llcrypt unregistered [ 6754.460645] Key type ._llcrypt registered [ 6754.461698] Key type .llcrypt registered [ 6754.709172] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6754.714113] alg: No test for adler32 (adler32-zlib) [ 6755.571398] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6755.653043] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6757.232129] Key type lgssc registered [ 6757.615111] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6761.386076] Lustre: DEBUG MARKER: Iteration 21 [ 6761.488055] LustreError: 323319:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6761.488063] LustreError: 323320:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6761.493137] LustreError: 323319:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 6762.292641] Lustre: Mounted lustre-client [ 6762.294295] Lustre: Skipped 1 previous similar message [ 6762.840956] LustreError: 323426:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e79cde800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6762.890684] Lustre: Unmounted lustre-client [ 6762.892257] Lustre: Skipped 1 previous similar message [ 6763.960273] Key type lgssc unregistered [ 6764.086534] LNet: 323669:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6764.088876] LNetError: 323669:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6764.097605] LNet: Removed LNI 192.168.201.44@tcp [ 6764.364117] Key type .llcrypt unregistered [ 6764.365253] Key type ._llcrypt unregistered [ 6764.679690] Key type ._llcrypt registered [ 6764.682574] Key type .llcrypt registered [ 6764.932409] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6764.938299] alg: No test for adler32 (adler32-zlib) [ 6765.823893] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6765.930695] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6767.536201] Key type lgssc registered [ 6767.933658] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6771.506132] Lustre: DEBUG MARKER: Iteration 22 [ 6771.634313] LustreError: 324491:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6771.634352] LustreError: 324492:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6771.638527] LustreError: 324491:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6772.443823] Lustre: Mounted lustre-client [ 6772.445139] Lustre: Skipped 1 previous similar message [ 6772.891720] LustreError: 324598:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e809bd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6772.895611] LustreError: 324598:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6772.911051] Lustre: Unmounted lustre-client [ 6773.863428] Key type lgssc unregistered [ 6773.970591] LNet: 324840:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6773.972740] LNetError: 324840:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6773.980699] LNet: Removed LNI 192.168.201.44@tcp [ 6774.185110] Key type .llcrypt unregistered [ 6774.186128] Key type ._llcrypt unregistered [ 6774.411685] Key type ._llcrypt registered [ 6774.412727] Key type .llcrypt registered [ 6774.596155] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6774.600950] alg: No test for adler32 (adler32-zlib) [ 6775.460130] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6775.542920] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6777.128122] Key type lgssc registered [ 6777.501826] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6781.021875] Lustre: DEBUG MARKER: Iteration 23 [ 6781.139467] LustreError: 325661:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6781.139748] LustreError: 325662:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6781.144221] LustreError: 325661:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6781.945106] Lustre: Mounted lustre-client [ 6781.947078] Lustre: Skipped 1 previous similar message [ 6782.509199] LustreError: 325763:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e79cda800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6782.514708] LustreError: 325763:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6782.527084] Lustre: Unmounted lustre-client [ 6783.528808] Key type lgssc unregistered [ 6783.657840] LNet: 326006:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6783.661433] LNetError: 326006:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6783.672250] LNet: Removed LNI 192.168.201.44@tcp [ 6783.930108] Key type .llcrypt unregistered [ 6783.931201] Key type ._llcrypt unregistered [ 6784.236138] Key type ._llcrypt registered [ 6784.238484] Key type .llcrypt registered [ 6784.505101] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6784.510324] alg: No test for adler32 (adler32-zlib) [ 6785.366624] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6785.451755] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6787.032149] Key type lgssc registered [ 6787.447131] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6791.023475] Lustre: DEBUG MARKER: Iteration 24 [ 6791.139730] LustreError: 326828:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6791.140100] LustreError: 326827:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6791.144773] LustreError: 326828:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6791.954115] Lustre: Mounted lustre-client [ 6791.956339] Lustre: Skipped 1 previous similar message [ 6792.543041] LustreError: 326934:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e496c4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6792.548899] LustreError: 326934:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6792.559643] Lustre: Unmounted lustre-client [ 6793.637072] Key type lgssc unregistered [ 6793.751481] LNet: 327177:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6793.753555] LNetError: 327177:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6793.762616] LNet: Removed LNI 192.168.201.44@tcp [ 6794.005122] Key type .llcrypt unregistered [ 6794.006268] Key type ._llcrypt unregistered [ 6794.307048] Key type ._llcrypt registered [ 6794.307980] Key type .llcrypt registered [ 6794.477994] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6794.484560] alg: No test for adler32 (adler32-zlib) [ 6795.358871] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6795.454958] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6797.048176] Key type lgssc registered [ 6797.451444] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6800.987980] Lustre: DEBUG MARKER: Iteration 25 [ 6801.097966] LustreError: 327999:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6801.098282] LustreError: 328000:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6801.104157] LustreError: 327999:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6801.906994] Lustre: Mounted lustre-client [ 6801.907892] Lustre: Skipped 1 previous similar message [ 6802.358963] LustreError: 328107:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e5879f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6802.363664] LustreError: 328107:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6802.387061] Lustre: Unmounted lustre-client [ 6803.432246] Key type lgssc unregistered [ 6803.544525] LNet: 328350:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6803.548208] LNetError: 328350:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6803.556186] LNet: Removed LNI 192.168.201.44@tcp [ 6803.798085] Key type .llcrypt unregistered [ 6803.799137] Key type ._llcrypt unregistered [ 6804.151299] Key type ._llcrypt registered [ 6804.152801] Key type .llcrypt registered [ 6804.387335] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6804.393585] alg: No test for adler32 (adler32-zlib) [ 6805.272541] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6805.360291] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6806.944149] Key type lgssc registered [ 6807.354647] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6810.864083] Lustre: DEBUG MARKER: Iteration 26 [ 6810.968974] LustreError: 329170:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6810.975114] LustreError: 329171:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6810.977077] LustreError: 329170:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 6811.781543] Lustre: Mounted lustre-client [ 6811.782793] Lustre: Skipped 1 previous similar message [ 6812.240371] LustreError: 329280:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e7b5ee800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6812.245367] LustreError: 329280:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6812.262618] Lustre: Unmounted lustre-client [ 6813.226808] Key type lgssc unregistered [ 6813.339393] LNet: 329522:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6813.342337] LNetError: 329522:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6813.351722] LNet: Removed LNI 192.168.201.44@tcp [ 6813.597127] Key type .llcrypt unregistered [ 6813.598894] Key type ._llcrypt unregistered [ 6813.925725] Key type ._llcrypt registered [ 6813.927023] Key type .llcrypt registered [ 6814.079929] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6814.085509] alg: No test for adler32 (adler32-zlib) [ 6814.944994] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6815.032520] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6816.624129] Key type lgssc registered [ 6817.044252] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6820.856408] Lustre: DEBUG MARKER: Iteration 27 [ 6820.982574] LustreError: 330342:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6820.983737] LustreError: 330343:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6820.988492] LustreError: 330342:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6821.799620] Lustre: Mounted lustre-client [ 6821.800952] Lustre: Skipped 1 previous similar message [ 6822.381425] LustreError: 330444:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e7b5e8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6822.426960] Lustre: Unmounted lustre-client [ 6822.428192] Lustre: Skipped 1 previous similar message [ 6823.474541] Key type lgssc unregistered [ 6823.591420] LNet: 330688:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6823.594585] LNetError: 330688:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6823.605488] LNet: Removed LNI 192.168.201.44@tcp [ 6823.867112] Key type .llcrypt unregistered [ 6823.868366] Key type ._llcrypt unregistered [ 6824.217090] Key type ._llcrypt registered [ 6824.218121] Key type .llcrypt registered [ 6824.481084] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6824.486581] alg: No test for adler32 (adler32-zlib) [ 6825.364693] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6825.468308] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6827.056170] Key type lgssc registered [ 6827.516429] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6831.125875] Lustre: DEBUG MARKER: Iteration 28 [ 6831.227496] LustreError: 331509:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6831.227773] LustreError: 331508:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6831.233763] LustreError: 331509:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6832.034260] Lustre: Mounted lustre-client [ 6832.577704] LustreError: 331615:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e79eab800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6832.582105] LustreError: 331615:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6832.600683] Lustre: Unmounted lustre-client [ 6833.614587] Key type lgssc unregistered [ 6833.729659] LNet: 331859:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6833.732627] LNetError: 331859:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6833.740315] LNet: Removed LNI 192.168.201.44@tcp [ 6833.966140] Key type .llcrypt unregistered [ 6833.967237] Key type ._llcrypt unregistered [ 6834.246168] Key type ._llcrypt registered [ 6834.247304] Key type .llcrypt registered [ 6834.419256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6834.424237] alg: No test for adler32 (adler32-zlib) [ 6835.280853] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6835.364374] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6836.944186] Key type lgssc registered [ 6837.346889] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6840.608048] Lustre: DEBUG MARKER: Iteration 29 [ 6840.707193] LustreError: 332679:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6840.707239] LustreError: 332680:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6840.712203] LustreError: 332679:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6841.504966] Lustre: Mounted lustre-client [ 6841.972189] LustreError: 332784:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e48f63000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6842.053451] Lustre: Unmounted lustre-client [ 6842.971348] Key type lgssc unregistered [ 6843.077576] LNet: 333027:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6843.079713] LNetError: 333027:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6843.087720] LNet: Removed LNI 192.168.201.44@tcp [ 6843.357102] Key type .llcrypt unregistered [ 6843.358225] Key type ._llcrypt unregistered [ 6843.693272] Key type ._llcrypt registered [ 6843.695218] Key type .llcrypt registered [ 6843.888101] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6843.894105] alg: No test for adler32 (adler32-zlib) [ 6844.769652] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6844.870722] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6846.456120] Key type lgssc registered [ 6846.819167] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6850.148183] Lustre: DEBUG MARKER: Iteration 30 [ 6850.260301] LustreError: 333848:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6850.260656] LustreError: 333849:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6850.265728] LustreError: 333848:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6851.070730] Lustre: Mounted lustre-client [ 6851.072644] Lustre: Skipped 1 previous similar message [ 6851.627537] LustreError: 333955:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e47c27800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6851.633053] LustreError: 333955:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6851.648139] Lustre: Unmounted lustre-client [ 6852.701957] Key type lgssc unregistered [ 6852.821639] LNet: 334198:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6852.823875] LNetError: 334198:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6852.832318] LNet: Removed LNI 192.168.201.44@tcp [ 6853.089109] Key type .llcrypt unregistered [ 6853.090530] Key type ._llcrypt unregistered [ 6853.403749] Key type ._llcrypt registered [ 6853.405113] Key type .llcrypt registered [ 6853.652162] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6853.658238] alg: No test for adler32 (adler32-zlib) [ 6854.522027] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6854.610169] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6856.200111] Key type lgssc registered [ 6856.564669] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6860.336766] Lustre: DEBUG MARKER: Iteration 31 [ 6860.455089] LustreError: 335019:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6860.455123] LustreError: 335020:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6860.459507] LustreError: 335019:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6861.255033] Lustre: Mounted lustre-client [ 6861.257055] Lustre: Skipped 1 previous similar message [ 6861.688637] LustreError: 335120:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e496c4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6861.693155] LustreError: 335120:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6861.741094] Lustre: Unmounted lustre-client [ 6862.703763] Key type lgssc unregistered [ 6862.830486] LNet: 335363:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6862.833041] LNetError: 335363:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6862.840691] LNet: Removed LNI 192.168.201.44@tcp [ 6863.061105] Key type .llcrypt unregistered [ 6863.062335] Key type ._llcrypt unregistered [ 6863.312942] Key type ._llcrypt registered [ 6863.313988] Key type .llcrypt registered [ 6863.500771] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6863.505638] alg: No test for adler32 (adler32-zlib) [ 6864.363810] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6864.445936] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6866.024163] Key type lgssc registered [ 6866.487616] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6870.157729] Lustre: DEBUG MARKER: Iteration 32 [ 6870.245903] LustreError: 336184:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6870.247399] LustreError: 336185:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6870.249919] LustreError: 336184:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6871.048066] Lustre: Mounted lustre-client [ 6871.049846] Lustre: Skipped 1 previous similar message [ 6871.487247] LustreError: 336293:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e43e7c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6871.490601] LustreError: 336293:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6871.517059] Lustre: Unmounted lustre-client [ 6872.432840] Key type lgssc unregistered [ 6872.548544] LNet: 336535:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6872.550700] LNetError: 336535:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6872.557666] LNet: Removed LNI 192.168.201.44@tcp [ 6872.795095] Key type .llcrypt unregistered [ 6872.796179] Key type ._llcrypt unregistered [ 6873.071403] Key type ._llcrypt registered [ 6873.072329] Key type .llcrypt registered [ 6873.232329] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6873.237836] alg: No test for adler32 (adler32-zlib) [ 6874.096510] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6874.184986] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6875.768215] Key type lgssc registered [ 6876.172406] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6879.490362] Lustre: DEBUG MARKER: Iteration 33 [ 6879.612483] LustreError: 337355:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6879.612763] LustreError: 337356:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6879.617074] LustreError: 337355:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6880.422695] Lustre: Mounted lustre-client [ 6880.424204] Lustre: Skipped 1 previous similar message [ 6880.967611] LustreError: 337463:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e47c22800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6880.972322] LustreError: 337463:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6881.006138] Lustre: Unmounted lustre-client [ 6882.068727] Key type lgssc unregistered [ 6882.177401] LNet: 337705:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6882.179835] LNetError: 337705:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6882.186802] LNet: Removed LNI 192.168.201.44@tcp [ 6882.400094] Key type .llcrypt unregistered [ 6882.401851] Key type ._llcrypt unregistered [ 6882.709475] Key type ._llcrypt registered [ 6882.714357] Key type .llcrypt registered [ 6882.961050] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6882.966307] alg: No test for adler32 (adler32-zlib) [ 6883.832658] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6883.923244] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6885.512199] Key type lgssc registered [ 6885.938514] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6889.322262] Lustre: DEBUG MARKER: Iteration 34 [ 6889.428792] LustreError: 338526:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6889.428820] LustreError: 338527:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6889.434570] LustreError: 338526:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6890.234499] Lustre: Mounted lustre-client [ 6890.685479] LustreError: 338627:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e4399c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6890.688321] LustreError: 338627:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6890.722689] Lustre: Unmounted lustre-client [ 6891.638899] Key type lgssc unregistered [ 6891.761461] LNet: 338871:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6891.763628] LNetError: 338871:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6891.769614] LNet: Removed LNI 192.168.201.44@tcp [ 6892.019096] Key type .llcrypt unregistered [ 6892.020474] Key type ._llcrypt unregistered [ 6892.285947] Key type ._llcrypt registered [ 6892.287418] Key type .llcrypt registered [ 6892.482688] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6892.487672] alg: No test for adler32 (adler32-zlib) [ 6893.358552] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6893.449915] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6895.040169] Key type lgssc registered [ 6895.459609] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6899.181233] Lustre: DEBUG MARKER: Iteration 35 [ 6899.300329] LustreError: 339691:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6899.300603] LustreError: 339692:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6899.304342] LustreError: 339691:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6900.109790] Lustre: Mounted lustre-client [ 6900.110808] Lustre: Skipped 1 previous similar message [ 6900.679721] LustreError: 339797:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e762cc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6900.733184] Lustre: Unmounted lustre-client [ 6901.699264] Key type lgssc unregistered [ 6901.806926] LNet: 340042:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6901.809641] LNetError: 340042:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6901.817743] LNet: Removed LNI 192.168.201.44@tcp [ 6902.080119] Key type .llcrypt unregistered [ 6902.081101] Key type ._llcrypt unregistered [ 6902.393194] Key type ._llcrypt registered [ 6902.394198] Key type .llcrypt registered [ 6902.681518] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6902.687178] alg: No test for adler32 (adler32-zlib) [ 6903.543516] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6903.622556] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6905.200129] Key type lgssc registered [ 6905.647540] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6909.089124] Lustre: DEBUG MARKER: Iteration 36 [ 6909.193698] LustreError: 340863:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6909.194913] LustreError: 340864:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6909.198980] LustreError: 340863:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6910.000780] Lustre: Mounted lustre-client [ 6910.002331] Lustre: Skipped 1 previous similar message [ 6910.456443] LustreError: 340973:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e496c3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6910.459903] LustreError: 340973:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6910.471513] Lustre: Unmounted lustre-client [ 6911.389418] Key type lgssc unregistered [ 6911.503376] LNet: 341215:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6911.507411] LNetError: 341215:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6911.516679] LNet: Removed LNI 192.168.201.44@tcp [ 6911.782137] Key type .llcrypt unregistered [ 6911.783192] Key type ._llcrypt unregistered [ 6912.088159] Key type ._llcrypt registered [ 6912.089213] Key type .llcrypt registered [ 6912.355860] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6912.362059] alg: No test for adler32 (adler32-zlib) [ 6913.230207] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6913.324537] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6914.912222] Key type lgssc registered [ 6915.318533] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6918.730235] Lustre: DEBUG MARKER: Iteration 37 [ 6918.829490] LustreError: 342035:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6918.829540] LustreError: 342036:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6918.833814] LustreError: 342035:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6919.630823] Lustre: Mounted lustre-client [ 6919.632377] Lustre: Skipped 1 previous similar message [ 6920.080793] LustreError: 342138:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e496c7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6920.085963] LustreError: 342138:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6920.107241] Lustre: Unmounted lustre-client [ 6921.092714] Key type lgssc unregistered [ 6921.197413] LNet: 342380:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6921.199622] LNetError: 342380:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6921.211635] LNet: Removed LNI 192.168.201.44@tcp [ 6921.447121] Key type .llcrypt unregistered [ 6921.448385] Key type ._llcrypt unregistered [ 6921.739765] Key type ._llcrypt registered [ 6921.743196] Key type .llcrypt registered [ 6921.922064] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6921.927905] alg: No test for adler32 (adler32-zlib) [ 6922.786759] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6922.871135] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6924.456113] Key type lgssc registered [ 6924.847210] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6928.543956] Lustre: DEBUG MARKER: Iteration 38 [ 6928.653993] LustreError: 343202:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6928.654123] LustreError: 343203:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6928.658991] LustreError: 343202:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6929.458702] Lustre: Mounted lustre-client [ 6929.459975] Lustre: Skipped 1 previous similar message [ 6929.913834] LustreError: 343305:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e4869f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6929.917935] LustreError: 343305:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6929.929082] Lustre: Unmounted lustre-client [ 6930.900858] Key type lgssc unregistered [ 6931.016577] LNet: 343547:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6931.019262] LNetError: 343547:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6931.026792] LNet: Removed LNI 192.168.201.44@tcp [ 6931.288115] Key type .llcrypt unregistered [ 6931.289712] Key type ._llcrypt unregistered [ 6931.601281] Key type ._llcrypt registered [ 6931.603191] Key type .llcrypt registered [ 6931.798980] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6931.805015] alg: No test for adler32 (adler32-zlib) [ 6932.657390] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6932.737226] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6934.320160] Key type lgssc registered [ 6934.730815] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6938.369711] Lustre: DEBUG MARKER: Iteration 39 [ 6938.470696] LustreError: 344367:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6938.471015] LustreError: 344368:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6938.474849] LustreError: 344367:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6939.275242] Lustre: Mounted lustre-client [ 6939.277062] Lustre: Skipped 1 previous similar message [ 6939.730751] LustreError: 344472:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e44f18800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6939.734902] LustreError: 344472:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6939.756228] Lustre: Unmounted lustre-client [ 6940.703186] Key type lgssc unregistered [ 6940.816460] LNet: 344717:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6940.818749] LNetError: 344717:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6940.829727] LNet: Removed LNI 192.168.201.44@tcp [ 6941.044112] Key type .llcrypt unregistered [ 6941.045372] Key type ._llcrypt unregistered [ 6941.375056] Key type ._llcrypt registered [ 6941.376093] Key type .llcrypt registered [ 6941.547852] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6941.552822] alg: No test for adler32 (adler32-zlib) [ 6942.417942] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6942.512310] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6944.096139] Key type lgssc registered [ 6944.484419] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6947.946693] Lustre: DEBUG MARKER: Iteration 40 [ 6948.047646] LustreError: 345537:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6948.047934] LustreError: 345538:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6948.053892] LustreError: 345537:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6948.853579] Lustre: Mounted lustre-client [ 6949.328036] LustreError: 345644:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e581d7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6949.332141] LustreError: 345644:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6949.386055] Lustre: Unmounted lustre-client [ 6950.303173] Key type lgssc unregistered [ 6950.418307] LNet: 345887:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6950.420760] LNetError: 345887:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6950.427637] LNet: Removed LNI 192.168.201.44@tcp [ 6950.644148] Key type .llcrypt unregistered [ 6950.645479] Key type ._llcrypt unregistered [ 6950.902390] Key type ._llcrypt registered [ 6950.903876] Key type .llcrypt registered [ 6951.108175] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6951.113841] alg: No test for adler32 (adler32-zlib) [ 6951.973099] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6952.054350] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6953.640138] Key type lgssc registered [ 6954.047828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6957.644212] Lustre: DEBUG MARKER: Iteration 41 [ 6957.748930] LustreError: 346707:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6957.750561] LustreError: 346708:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6957.753828] LustreError: 346707:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6958.550446] Lustre: Mounted lustre-client [ 6958.551836] Lustre: Skipped 1 previous similar message [ 6958.988858] LustreError: 346810:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e45d3d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6958.991788] LustreError: 346810:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6959.023341] Lustre: Unmounted lustre-client [ 6959.902561] Key type lgssc unregistered [ 6960.015460] LNet: 347054:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6960.017638] LNetError: 347054:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6960.023649] LNet: Removed LNI 192.168.201.44@tcp [ 6960.258101] Key type .llcrypt unregistered [ 6960.259338] Key type ._llcrypt unregistered [ 6960.583476] Key type ._llcrypt registered [ 6960.585311] Key type .llcrypt registered [ 6960.732186] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6960.737239] alg: No test for adler32 (adler32-zlib) [ 6961.593421] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6961.674459] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6963.256145] Key type lgssc registered [ 6963.673534] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6967.150134] Lustre: DEBUG MARKER: Iteration 42 [ 6967.256352] LustreError: 347875:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6967.257086] LustreError: 347876:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6967.262017] LustreError: 347875:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6968.069905] Lustre: Mounted lustre-client [ 6968.071510] Lustre: Skipped 1 previous similar message [ 6968.654988] LustreError: 347981:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e581d3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6968.658533] LustreError: 347981:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6968.694127] Lustre: Unmounted lustre-client [ 6969.694362] Key type lgssc unregistered [ 6969.814516] LNet: 348224:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6969.817169] LNetError: 348224:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6969.826356] LNet: Removed LNI 192.168.201.44@tcp [ 6970.081117] Key type .llcrypt unregistered [ 6970.082164] Key type ._llcrypt unregistered [ 6970.375817] Key type ._llcrypt registered [ 6970.377128] Key type .llcrypt registered [ 6970.602242] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6970.617682] alg: No test for adler32 (adler32-zlib) [ 6971.487579] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6971.591982] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6973.184143] Key type lgssc registered [ 6973.600599] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6977.467964] Lustre: DEBUG MARKER: Iteration 43 [ 6977.566292] LustreError: 349043:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6977.566338] LustreError: 349044:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6977.571682] LustreError: 349043:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6978.373737] Lustre: Mounted lustre-client [ 6978.884774] LustreError: 349152:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e5879c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6978.927094] Lustre: Unmounted lustre-client [ 6979.980206] Key type lgssc unregistered [ 6980.105701] LNet: 349394:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6980.108463] LNetError: 349394:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6980.116331] LNet: Removed LNI 192.168.201.44@tcp [ 6980.368144] Key type .llcrypt unregistered [ 6980.369705] Key type ._llcrypt unregistered [ 6980.746696] Key type ._llcrypt registered [ 6980.768663] Key type .llcrypt registered [ 6981.015384] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6981.020606] alg: No test for adler32 (adler32-zlib) [ 6981.886266] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6981.972705] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6983.560197] Key type lgssc registered [ 6983.982020] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6987.501269] Lustre: DEBUG MARKER: Iteration 44 [ 6987.603641] LustreError: 350217:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6987.604762] LustreError: 350216:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6987.607887] LustreError: 350217:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6988.407758] Lustre: Mounted lustre-client [ 6988.842508] LustreError: 350324:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e6dfe5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6988.846194] LustreError: 350324:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6988.864073] Lustre: Unmounted lustre-client [ 6989.864505] Key type lgssc unregistered [ 6989.976591] LNet: 350567:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6989.980251] LNetError: 350567:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6989.990742] LNet: Removed LNI 192.168.201.44@tcp [ 6990.218099] Key type .llcrypt unregistered [ 6990.219130] Key type ._llcrypt unregistered [ 6990.494823] Key type ._llcrypt registered [ 6990.495863] Key type .llcrypt registered [ 6990.674821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6990.679750] alg: No test for adler32 (adler32-zlib) [ 6991.524042] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 6991.603301] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 6993.184130] Key type lgssc registered [ 6993.553653] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6997.184512] Lustre: DEBUG MARKER: Iteration 45 [ 6997.310397] LustreError: 351387:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 6997.310452] LustreError: 351388:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 6997.315309] LustreError: 351387:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6998.118196] Lustre: Mounted lustre-client [ 6998.562467] LustreError: 351494:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e69ea1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6998.566268] LustreError: 351494:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6998.599604] Lustre: Unmounted lustre-client [ 6999.530129] Key type lgssc unregistered [ 6999.641518] LNet: 351735:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6999.644529] LNetError: 351735:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6999.651793] LNet: Removed LNI 192.168.201.44@tcp [ 6999.883089] Key type .llcrypt unregistered [ 6999.884419] Key type ._llcrypt unregistered [ 7000.149275] Key type ._llcrypt registered [ 7000.151705] Key type .llcrypt registered [ 7000.355384] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7000.360849] alg: No test for adler32 (adler32-zlib) [ 7001.223310] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 7001.310669] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 7002.896190] Key type lgssc registered [ 7003.287445] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7006.677603] Lustre: DEBUG MARKER: Iteration 46 [ 7006.784291] LustreError: 352557:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7006.784333] LustreError: 352556:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7006.790252] LustreError: 352557:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7007.585047] Lustre: Mounted lustre-client [ 7007.586039] Lustre: Skipped 1 previous similar message [ 7008.035078] LustreError: 352662:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e4596c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7008.039544] LustreError: 352662:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7008.053059] Lustre: Unmounted lustre-client [ 7009.002329] Key type lgssc unregistered [ 7009.116528] LNet: 352905:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7009.119289] LNetError: 352905:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7009.127587] LNet: Removed LNI 192.168.201.44@tcp [ 7009.365114] Key type .llcrypt unregistered [ 7009.366176] Key type ._llcrypt unregistered [ 7009.626131] Key type ._llcrypt registered [ 7009.627985] Key type .llcrypt registered [ 7009.816980] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7009.822578] alg: No test for adler32 (adler32-zlib) [ 7010.686519] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 7010.769349] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 7012.352103] Key type lgssc registered [ 7012.737610] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7016.300559] Lustre: DEBUG MARKER: Iteration 47 [ 7016.411312] LustreError: 353725:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7016.411367] LustreError: 353726:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7016.416962] LustreError: 353725:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7017.224714] Lustre: Mounted lustre-client [ 7017.226434] Lustre: Skipped 1 previous similar message [ 7017.703833] LustreError: 353835:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e60174000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7017.744860] Lustre: Unmounted lustre-client [ 7018.757295] Key type lgssc unregistered [ 7018.883806] LNet: 354078:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7018.886192] LNetError: 354078:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7018.894223] LNet: Removed LNI 192.168.201.44@tcp [ 7019.156115] Key type .llcrypt unregistered [ 7019.157128] Key type ._llcrypt unregistered [ 7019.516263] Key type ._llcrypt registered [ 7019.517352] Key type .llcrypt registered [ 7019.739838] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7019.745795] alg: No test for adler32 (adler32-zlib) [ 7020.598175] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 7020.682641] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 7022.272264] Key type lgssc registered [ 7022.711100] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7026.275026] Lustre: DEBUG MARKER: Iteration 48 [ 7026.396875] LustreError: 354898:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7026.397207] LustreError: 354900:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7026.400569] LustreError: 354898:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7027.225831] Lustre: Mounted lustre-client [ 7027.227533] Lustre: Skipped 1 previous similar message [ 7027.771883] LustreError: 355000:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e451c3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7027.804417] Lustre: Unmounted lustre-client [ 7028.850701] Key type lgssc unregistered [ 7028.992836] LNet: 355243:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7029.000458] LNetError: 355243:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7029.013711] LNet: Removed LNI 192.168.201.44@tcp [ 7029.345154] Key type .llcrypt unregistered [ 7029.346977] Key type ._llcrypt unregistered [ 7029.759603] Key type ._llcrypt registered [ 7029.760602] Key type .llcrypt registered [ 7029.991598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7029.996565] alg: No test for adler32 (adler32-zlib) [ 7030.864751] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 7030.952286] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 7032.544209] Key type lgssc registered [ 7032.979221] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7036.582450] Lustre: DEBUG MARKER: Iteration 49 [ 7036.706425] LustreError: 356063:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7036.706779] LustreError: 356064:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7036.711638] LustreError: 356063:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7037.538469] Lustre: Mounted lustre-client [ 7038.124750] LustreError: 356171:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e5e942000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7038.128045] LustreError: 356171:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7038.151583] Lustre: Unmounted lustre-client [ 7039.222985] Key type lgssc unregistered [ 7039.349577] LNet: 356414:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7039.353852] LNetError: 356414:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7039.362899] LNet: Removed LNI 192.168.201.44@tcp [ 7039.674155] Key type .llcrypt unregistered [ 7039.676134] Key type ._llcrypt unregistered [ 7040.030795] Key type ._llcrypt registered [ 7040.031980] Key type .llcrypt registered [ 7040.315104] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7040.319940] alg: No test for adler32 (adler32-zlib) [ 7041.184995] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 7041.277660] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 7042.864205] Key type lgssc registered [ 7043.396210] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7047.324242] Lustre: DEBUG MARKER: Iteration 50 [ 7047.426567] LustreError: 357234:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7047.431881] LustreError: 357235:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7047.433952] LustreError: 357234:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7048.233284] Lustre: Mounted lustre-client [ 7048.756883] LustreError: 357341:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e5879e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7048.759861] LustreError: 357341:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7048.802849] Lustre: Unmounted lustre-client [ 7048.804052] Lustre: Skipped 1 previous similar message [ 7049.853402] Key type lgssc unregistered [ 7049.981537] LNet: 357585:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7049.983507] LNetError: 357585:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7049.991759] LNet: Removed LNI 192.168.201.44@tcp [ 7050.252167] Key type .llcrypt unregistered [ 7050.254069] Key type ._llcrypt unregistered [ 7050.563242] Key type ._llcrypt registered [ 7050.564434] Key type .llcrypt registered [ 7050.799415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7050.804550] alg: No test for adler32 (adler32-zlib) [ 7051.669318] Lustre: Lustre: Build Version: 2.17.50_193_gf215d40 [ 7051.751952] LNet: Added LNI 192.168.201.44@tcp [8/256/0/180] [ 7053.336266] Key type lgssc registered [ 7053.717528] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7057.723492] Lustre: Mounted lustre-client [ 7060.325972] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 03:44:33 (1772441073) [ 7076.832139] Lustre: 358917:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772441074/real 1772441074] req@ffff8c9e5c537480 x1858539162182400/t0(0) o36->lustre-MDT0000-mdc-ffff8c9e5c68b800@192.168.201.144@tcp:12/10 lens 496/440 e 0 to 1 dl 1772441090 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7076.840901] Lustre: lustre-MDT0000-mdc-ffff8c9e5c68b800: Connection to lustre-MDT0000 (at 192.168.201.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7076.851986] Lustre: lustre-MDT0000-mdc-ffff8c9e5c68b800: Connection restored to 192.168.201.144@tcp (at 192.168.201.144@tcp) [ 7093.216103] Lustre: 358917:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772441090/real 1772441090] req@ffff8c9e5c537480 x1858539162182400/t0(0) o36->lustre-MDT0000-mdc-ffff8c9e5c68b800@192.168.201.144@tcp:12/10 lens 496/440 e 0 to 1 dl 1772441106 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7093.223340] Lustre: lustre-MDT0000-mdc-ffff8c9e5c68b800: Connection to lustre-MDT0000 (at 192.168.201.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7093.231438] Lustre: lustre-MDT0000-mdc-ffff8c9e5c68b800: Connection restored to 192.168.201.144@tcp (at 192.168.201.144@tcp) [ 7108.576113] Lustre: 358917:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772441106/real 1772441106] req@ffff8c9e5c537480 x1858539162182400/t0(0) o36->lustre-MDT0000-mdc-ffff8c9e5c68b800@192.168.201.144@tcp:12/10 lens 496/440 e 0 to 1 dl 1772441122 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7108.583129] Lustre: lustre-MDT0000-mdc-ffff8c9e5c68b800: Connection to lustre-MDT0000 (at 192.168.201.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7108.592604] Lustre: lustre-MDT0000-mdc-ffff8c9e5c68b800: Connection restored to 192.168.201.144@tcp (at 192.168.201.144@tcp) [ 7124.960168] Lustre: 358917:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772441122/real 1772441122] req@ffff8c9e5c537480 x1858539162182400/t0(0) o36->lustre-MDT0000-mdc-ffff8c9e5c68b800@192.168.201.144@tcp:12/10 lens 496/440 e 0 to 1 dl 1772441138 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7124.967681] Lustre: lustre-MDT0000-mdc-ffff8c9e5c68b800: Connection to lustre-MDT0000 (at 192.168.201.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7124.977956] Lustre: lustre-MDT0000-mdc-ffff8c9e5c68b800: Connection restored to 192.168.201.144@tcp (at 192.168.201.144@tcp) [ 7125.497003] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 03:45:38 (1772441138) [ 7126.027350] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 7126.629244] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 03:45:39 (1772441139) [ 7127.166610] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 7127.795548] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 03:45:40 (1772441140) [ 7130.025153] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 03:45:42 (1772441142) [ 7130.548696] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 7131.124850] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 03:45:44 (1772441144) [ 7131.630406] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 7132.223369] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 03:45:45 (1772441145) [ 7132.752074] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 7133.357602] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 03:45:46 (1772441146) [ 7133.429865] LustreError: 361570:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 7135.512135] LustreError: 361570:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 7137.785391] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 03:45:50 (1772441150) [ 7155.680127] Lustre: 357780:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772441153/real 1772441153] req@ffff8c9e76256300 x1858539162214656/t0(0) o4->lustre-OST0000-osc-ffff8c9e5c68b800@192.168.201.144@tcp:6/4 lens 4584/448 e 0 to 1 dl 1772441169 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7155.680163] Lustre: lustre-OST0000-osc-ffff8c9e5c68b800: Connection to lustre-OST0000 (at 192.168.201.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7155.687269] Lustre: 357780:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 7155.696308] Lustre: lustre-OST0000-osc-ffff8c9e5c68b800: Connection restored to 192.168.201.144@tcp (at 192.168.201.144@tcp) [ 7172.064123] Lustre: 357779:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772441169/real 1772441169] req@ffff8c9e76254a80 x1858539162214016/t0(0) o4->lustre-OST0000-osc-ffff8c9e5c68b800@192.168.201.144@tcp:6/4 lens 4584/448 e 0 to 1 dl 1772441185 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7172.064176] Lustre: lustre-OST0000-osc-ffff8c9e5c68b800: Connection to lustre-OST0000 (at 192.168.201.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7172.072031] Lustre: 357779:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 7172.080794] Lustre: lustre-OST0000-osc-ffff8c9e5c68b800: Connection restored to 192.168.201.144@tcp (at 192.168.201.144@tcp) [ 7188.256103] Lustre: 357779:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772441185/real 1772441185] req@ffff8c9e76254a80 x1858539162214016/t0(0) o4->lustre-OST0000-osc-ffff8c9e5c68b800@192.168.201.144@tcp:6/4 lens 4584/448 e 0 to 1 dl 1772441201 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7188.267965] Lustre: lustre-OST0000-osc-ffff8c9e5c68b800: Connection to lustre-OST0000 (at 192.168.201.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7188.282157] Lustre: lustre-OST0000-osc-ffff8c9e5c68b800: Connection restored to 192.168.201.144@tcp (at 192.168.201.144@tcp) [ 7203.530384] Lustre: DEBUG MARKER: oleg144-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8c9e5c68b800.ost_server_uuid 50 [ 7204.079964] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8c9e5c68b800.ost_server_uuid in FULL state after 0 sec [ 7204.673266] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 7205.240839] Lustre: DEBUG MARKER: == sanityn test complete, duration 6912 sec ============== 03:46:58 (1772441218) [ 7205.804662] Lustre: DEBUG MARKER: === sanityn: start cleanup 03:46:58 (1772441218) === [ 7255.204440] LustreError: 363559:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e729df800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7255.217065] Lustre: Unmounted lustre-client [ 7256.464434] Lustre: DEBUG MARKER: === sanityn: finish cleanup 03:47:49 (1772441269) === [ 7256.770481] LustreError: 363860:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c9e5c68b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7256.773772] LustreError: 363860:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7256.794823] Lustre: Unmounted lustre-client [ 7284.849371] Key type lgssc unregistered [ 7284.972778] LNet: 364343:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7284.975810] LNetError: 364343:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7284.986567] LNet: Removed LNI 192.168.201.44@tcp [ 7285.237101] Key type .llcrypt unregistered [ 7285.238111] Key type ._llcrypt unregistered