[ 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.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 427658064 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 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001009] APIC: Switch to symmetric I/O mode setup [ 0.002309] x2apic enabled [ 0.003004] Switched APIC routing to physical x2apic. [ 0.004008] kvm-guest: setup PV IPIs [ 0.006362] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007017] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008007] pid_max: default: 32768 minimum: 301 [ 0.009120] LSM: Security Framework initializing [ 0.010036] Yama: becoming mindful. [ 0.010901] SELinux: Initializing. [ 0.011050] *** VALIDATE selinux *** [ 0.019004] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023174] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024180] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.025111] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026147] *** VALIDATE tmpfs *** [ 0.028071] *** VALIDATE proc *** [ 0.029064] *** VALIDATE cgroup *** [ 0.029923] *** VALIDATE cgroup2 *** [ 0.031050] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032136] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034020] Spectre V2 : User space: Vulnerable [ 0.035004] Speculative Store Bypass: Vulnerable [ 0.038441] debug: unmapping init [mem 0xffffffffb2e59000-0xffffffffb2e60fff] [ 0.040123] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.041491] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.042012] ... version: 2 [ 0.042935] ... bit width: 48 [ 0.043007] ... generic registers: 4 [ 0.043981] ... value mask: 0000ffffffffffff [ 0.044007] ... max period: 00007fffffffffff [ 0.045007] ... fixed-purpose events: 3 [ 0.045997] ... event mask: 000000070000000f [ 0.046243] rcu: Hierarchical SRCU implementation. [ 0.048287] smp: Bringing up secondary CPUs ... [ 0.049423] x86: Booting SMP configuration: [ 0.050012] .... node #0, CPUs: #1 #2 #3 [ 0.052345] smp: Brought up 1 node, 4 CPUs [ 0.054008] smpboot: Max logical packages: 1 [ 0.055010] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.230021] node 0 deferred pages initialised in 174ms [ 0.233163] devtmpfs: initialized [ 0.234200] x86/mm: Memory block size: 128MB [ 0.236205] gcov: version magic: 0x41383552 [ 0.238220] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.240083] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.242267] pinctrl core: initialized pinctrl subsystem [ 0.244138] [ 0.244388] ************************************************************* [ 0.245006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.247007] ** ** [ 0.249008] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.250006] ** ** [ 0.252006] ** This means that this kernel is built to expose internal ** [ 0.253007] ** IOMMU data structures, which may compromise security on ** [ 0.255008] ** your system. ** [ 0.257010] ** ** [ 0.259008] ** If you see this message and you are not debugging the ** [ 0.260005] ** kernel, report this immediately to your vendor! ** [ 0.262008] ** ** [ 0.264007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.265005] ************************************************************* [ 0.266509] NET: Registered protocol family 16 [ 0.268298] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.270026] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.272038] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.275120] cpuidle: using governor menu [ 0.276404] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.277411] PCI: Using configuration type 1 for base access [ 0.279100] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.286119] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.288011] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.290031] cryptd: max_cpu_qlen set to 1000 [ 0.291636] ACPI: Added _OSI(Module Device) [ 0.293009] ACPI: Added _OSI(Processor Device) [ 0.293878] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.294006] ACPI: Added _OSI(Processor Aggregator Device) [ 0.298025] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.302584] ACPI: Interpreter enabled [ 0.304042] ACPI: PM: (supports S0 S3 S4 S5) [ 0.305009] ACPI: Using IOAPIC for interrupt routing [ 0.306085] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.308360] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.315832] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.317022] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.319008] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.321051] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.323869] acpiphp: Slot [2] registered [ 0.325100] acpiphp: Slot [3] registered [ 0.325837] acpiphp: Slot [4] registered [ 0.326036] acpiphp: Slot [5] registered [ 0.326809] acpiphp: Slot [6] registered [ 0.328077] acpiphp: Slot [7] registered [ 0.329063] acpiphp: Slot [8] registered [ 0.329978] acpiphp: Slot [9] registered [ 0.331045] acpiphp: Slot [10] registered [ 0.331676] acpiphp: Slot [11] registered [ 0.332042] acpiphp: Slot [12] registered [ 0.332697] acpiphp: Slot [13] registered [ 0.334047] acpiphp: Slot [14] registered [ 0.334908] acpiphp: Slot [15] registered [ 0.335044] acpiphp: Slot [16] registered [ 0.335722] acpiphp: Slot [17] registered [ 0.337110] acpiphp: Slot [18] registered [ 0.338066] acpiphp: Slot [19] registered [ 0.338831] acpiphp: Slot [20] registered [ 0.340048] acpiphp: Slot [21] registered [ 0.340842] acpiphp: Slot [22] registered [ 0.342079] acpiphp: Slot [23] registered [ 0.343065] acpiphp: Slot [24] registered [ 0.344043] acpiphp: Slot [25] registered [ 0.345041] acpiphp: Slot [26] registered [ 0.346029] acpiphp: Slot [27] registered [ 0.347055] acpiphp: Slot [28] registered [ 0.348075] acpiphp: Slot [29] registered [ 0.349057] acpiphp: Slot [30] registered [ 0.350053] acpiphp: Slot [31] registered [ 0.351039] PCI host bridge to bus 0000:00 [ 0.351889] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.353009] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.354008] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.355008] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.356010] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.358010] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.359127] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.361386] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.363508] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.368355] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.370765] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.372011] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.373009] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.374007] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.375373] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.377536] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.379028] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.381404] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.384008] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.390012] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.395015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.399748] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.406026] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.412020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.422020] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.429618] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.436014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.441025] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.452015] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.460421] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.462219] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.464353] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.466223] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.468166] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.473072] iommu: Default domain type: Passthrough [ 0.474524] SCSI subsystem initialized [ 0.475124] ACPI: bus type USB registered [ 0.476089] usbcore: registered new interface driver usbfs [ 0.478059] usbcore: registered new interface driver hub [ 0.480059] usbcore: registered new device driver usb [ 0.481132] pps_core: LinuxPPS API ver. 1 registered [ 0.483006] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.485060] PTP clock support registered [ 0.486186] EDAC MC: Ver: 3.0.0 [ 0.488373] PCI: Using ACPI for IRQ routing [ 0.489601] NetLabel: Initializing [ 0.491008] NetLabel: domain hash size = 128 [ 0.492008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.493062] NetLabel: unlabeled traffic allowed by default [ 0.495113] vgaarb: loaded [ 0.496299] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.498008] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.501347] clocksource: Switched to clocksource kvm-clock [ 0.583921] VFS: Disk quotas dquot_6.6.0 [ 0.584994] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.587541] *** VALIDATE ramfs *** [ 0.588806] *** VALIDATE hugetlbfs *** [ 0.589806] pnp: PnP ACPI init [ 0.591862] pnp: PnP ACPI: found 6 devices [ 0.604547] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.606730] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.608211] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.609317] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.610556] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.611939] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.613478] NET: Registered protocol family 2 [ 0.615095] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.618415] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.620857] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.624423] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.626611] TCP: Hash tables configured (established 65536 bind 65536) [ 0.628537] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.630502] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.632253] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.634572] NET: Registered protocol family 1 [ 0.636339] RPC: Registered named UNIX socket transport module. [ 0.637993] RPC: Registered udp transport module. [ 0.639106] RPC: Registered tcp transport module. [ 0.640220] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.641772] NET: Registered protocol family 44 [ 0.643040] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.644799] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.646797] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.648409] PCI: CLS 0 bytes, default 64 [ 0.649480] Unpacking initramfs... [ 1.884790] debug: unmapping init [mem 0xffff8b1b3cc64000-0xffff8b1b3ffcffff] [ 1.890365] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.892222] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 1.894473] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.355383] Initialise system trusted keyrings [ 2.356817] Key type blacklist registered [ 2.358924] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.367546] zbud: loaded [ 2.370970] *** VALIDATE nfs *** [ 2.372181] *** VALIDATE nfs4 *** [ 2.373399] pstore: using deflate compression [ 2.376647] Platform Keyring initialized [ 2.512688] NET: Registered protocol family 38 [ 2.514414] Key type asymmetric registered [ 2.515478] Asymmetric key parser 'x509' registered [ 2.516881] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.518942] io scheduler mq-deadline registered [ 2.520722] io scheduler kyber registered [ 2.522236] io scheduler bfq registered [ 2.524122] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.526979] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.529235] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.532226] ACPI: Power Button [PWRF] [ 2.644180] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.737391] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.844413] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.875120] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.908336] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.913869] Non-volatile memory driver v1.3 [ 2.915653] Linux agpgart interface v0.103 [ 2.952283] virtio_blk virtio1: [vda] 133056 512-byte logical blocks (68.1 MB/65.0 MiB) [ 2.955137] vda: detected capacity change from 0 to 68124672 [ 2.970585] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.973123] vdb: detected capacity change from 0 to 1073741824 [ 2.980486] libphy: Fixed MDIO Bus: probed [ 2.990092] usbcore: registered new interface driver usbserial_generic [ 2.992640] usbserial: USB Serial support registered for generic [ 2.995599] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.999151] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.000644] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.003071] mousedev: PS/2 mouse device common for all mice [ 3.006845] rtc_cmos 00:05: RTC can wake from S4 [ 3.010105] rtc_cmos 00:05: registered as rtc0 [ 3.010411] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.012074] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.018155] intel_pstate: CPU model not supported [ 3.019701] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.028600] hid: raw HID events driver (C) Jiri Kosina [ 3.029189] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.032104] usbcore: registered new interface driver usbhid [ 3.036944] usbhid: USB HID core driver [ 3.038487] drop_monitor: Initializing network drop monitor service [ 3.041111] Initializing XFRM netlink socket [ 3.043270] NET: Registered protocol family 10 [ 3.046417] Segment Routing with IPv6 [ 3.047973] NET: Registered protocol family 17 [ 3.050167] mpls_gso: MPLS GSO support [ 3.056067] RAS: Correctable Errors collector initialized. [ 3.058333] AVX version of gcm_enc/dec engaged. [ 3.059809] AES CTR mode by8 optimization enabled [ 3.191531] sched_clock: Marking stable (3191505339, 0)->(3947988079, -756482740) [ 3.196686] registered taskstats version 1 [ 3.199284] Loading compiled-in X.509 certificates [ 3.201823] zswap: loaded using pool lzo/zbud [ 3.234575] Key type big_key registered [ 3.247465] Key type encrypted registered [ 3.248918] ima: No TPM chip found, activating TPM-bypass! [ 3.250787] ima: Allocated hash algorithm: sha1 [ 3.251995] ima: No architecture policies found [ 3.254111] evm: Initialising EVM extended attributes: [ 3.256893] evm: security.selinux [ 3.258309] evm: security.ima [ 3.259470] evm: security.capability [ 3.260822] evm: HMAC attrs: 0x1 [ 3.263866] rtc_cmos 00:05: setting system clock to 2025-09-02 10:33:45 UTC (1756809225) [ 3.271692] debug: unmapping init [mem 0xffffffffb3e03000-0xffffffffb3ffffff] [ 3.274675] debug: unmapping init [mem 0xffffffffb2b82000-0xffffffffb2e58fff] [ 3.284929] Write protecting the kernel read-only data: 28672k [ 3.291307] debug: unmapping init [mem 0xffffffffb1203000-0xffffffffb13fffff] [ 3.294653] debug: unmapping init [mem 0xffffffffb1b14000-0xffffffffb1bfffff] [ 3.324977] 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.331351] systemd[1]: Detected virtualization kvm. [ 3.332888] systemd[1]: Detected architecture x86-64. [ 3.334323] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.360680] systemd[1]: No hostname configured. [ 3.362965] systemd[1]: Set hostname to . [ 3.367467] random: systemd: uninitialized urandom read (16 bytes read) [ 3.372819] systemd[1]: Initializing machine ID from random generator. [ 3.425304] random: ln: uninitialized urandom read (6 bytes read) [ 3.580288] random: systemd: uninitialized urandom read (16 bytes read) [ 3.583149] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.594304] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.606333] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Timers. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.529271] device-mapper: uevent: version 1.0.3 [ 4.531357] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.919186] virtio_net virtio0 ens2: renamed from eth0 [ 6.061835] scsi host0: ata_piix [ 6.126110] scsi host1: ata_piix [ 6.139482] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.144063] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.744626] random: fast init done [ 10.788615] random: crng init done [ 10.791506] random: 7 urandom warning(s) missed due to ratelimiting [ 12.313365] dracut-initqueue[586]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 14.854201] 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 Timers. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ 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 Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 18.484163] printk: systemd: 25 output lines suppressed due to ratelimiting [ 18.951364] SELinux: Disabled at runtime. [ 19.021654] 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) [ 19.029466] systemd[1]: Detected virtualization kvm. [ 19.031137] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 20.104287] systemd[1]: initrd-switch-root.service: Succeeded. [ 20.117426] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 20.124343] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 20.129498] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 20.141355] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 20.164865] systemd[1]: Starting Journal Service... Starting Journal Service... [ 20.175507] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Switch Root. Starting Remount Root and Kernel File Systems... Activating swap /dev/disk/by-label/SWAP... Starting Create list of required st…ce nodes for the current kernel... Mounting Kernel Debug File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ 20.506505] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Huge Pages File System... [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting POSIX Message Queue File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Apply Kernel Variables... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 21.979331] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 23.325962] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 23.711099] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 24.167123] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 24.243279] EDAC sbridge: Ver: 1.1.2 [ 27.248186] Key type dns_resolver registered [ 27.774231] NFS: Registering the id_resolver key type [ 27.776678] Key type id_resolver registered [ 27.779751] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting 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 Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... 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 oleg237-client login: [ 44.488348] hrtimer: interrupt took 5300855 ns [ 83.296635] libcfs: loading out-of-tree module taints kernel. [ 83.328161] Key type ._llcrypt registered [ 83.329669] Key type .llcrypt registered [ 83.677160] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 83.691726] alg: No test for adler32 (adler32-zlib) [ 84.823330] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 85.205980] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 86.848272] Key type lgssc registered [ 87.444416] Lustre: Echo OBD driver; http://www.lustre.org/ [ 163.857878] Lustre: Mounted lustre-client [ 167.000746] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 177.828707] Lustre: DEBUG MARKER: oleg237-client.virtnet: executing check_logdir /tmp/testlogs/ [ 179.617920] Lustre: DEBUG MARKER: oleg237-client.virtnet: executing yml_node [ 181.586637] Lustre: DEBUG MARKER: Client: 2.16.58.1 [ 182.800084] Lustre: DEBUG MARKER: MDS: 2.16.58.1 [ 184.095647] Lustre: DEBUG MARKER: OSS: 2.16.58.1 [ 185.136022] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Tue Sep 2 06:36:46 EDT 2025 [ 189.408241] Lustre: lustre-OST0000-osc-ffff8b1b85b4c000: disconnect after 24s idle [ 194.460798] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 195.218874] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 196.036575] Lustre: DEBUG MARKER: === sanityn: start setup 06:36:57 (1756809417) === [ 196.423499] Lustre: Mounted lustre-client [ 198.635210] Lustre: DEBUG MARKER: oleg237-client.virtnet: executing check_config_client /mnt/lustre [ 210.004307] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 216.947130] Lustre: DEBUG MARKER: === sanityn: finish setup 06:37:18 (1756809438) === [ 217.056525] Lustre: lustre-OST0000-osc-ffff8b1b84f7c000: disconnect after 21s idle [ 217.060722] Lustre: Skipped 1 previous similar message [ 218.322247] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 06:37:19 (1756809439) [ 223.221609] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 06:37:24 (1756809444) [ 226.998370] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 06:37:28 (1756809448) [ 230.384736] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 06:37:31 (1756809451) [ 233.602416] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 06:37:35 (1756809455) [ 237.007260] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 06:37:38 (1756809458) [ 240.844105] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 06:37:42 (1756809462) [ 245.031280] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 06:37:46 (1756809466) [ 250.020921] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 06:37:51 (1756809471) [ 254.550657] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 06:37:55 (1756809475) [ 258.651872] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 06:37:59 (1756809479) [ 263.821487] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 06:38:05 (1756809485) [ 268.245995] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 06:38:09 (1756809489) [ 272.667682] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 06:38:14 (1756809494) [ 277.062138] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 06:38:18 (1756809498) [ 281.279623] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 06:38:22 (1756809502) [ 286.048336] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 06:38:27 (1756809507) [ 290.064119] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 06:38:31 (1756809511) [ 294.311223] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 06:38:35 (1756809515) [ 298.092434] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 06:38:39 (1756809519) [ 302.621277] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 06:38:43 (1756809523) [ 303.118475] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 451.115422] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 06:41:12 (1756809672) [ 454.815800] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 06:41:16 (1756809676) [ 457.932641] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 06:41:19 (1756809679) [ 461.215808] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 06:41:22 (1756809682) [ 464.212290] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 06:41:25 (1756809685) [ 467.579255] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 06:41:29 (1756809689) [ 468.612109] Lustre: DEBUG MARKER: chmod [ 471.912395] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 06:41:33 (1756809693) [ 1019.173948] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 06:50:40 (1756810240) [ 1128.416382] Lustre: lustre-OST0000-osc-ffff8b1b84f7c000: disconnect after 23s idle [ 1128.423137] Lustre: Skipped 1 previous similar message [ 1177.335450] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 06:53:18 (1756810398) [ 1259.057652] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 06:54:40 (1756810480) [ 1349.403565] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 06:56:11 (1756810571) [ 1366.540660] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 06:56:28 (1756810588) [ 1369.490361] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 06:56:31 (1756810591) [ 1369.857552] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1369.889881] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1369.918767] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1369.944827] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1369.970746] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.002276] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.031631] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.063859] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.095031] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.122829] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.151475] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.178672] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.205152] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.231471] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.260878] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.287866] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.314873] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.341590] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.368183] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.398720] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.429921] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.458729] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.485082] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.514856] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.542493] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.568510] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.600577] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.635610] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.667066] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.696364] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.722693] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.751450] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.778636] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.803731] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.830449] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.858650] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.885923] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.917228] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.944300] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1370.977410] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.008088] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.040905] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.067263] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.094345] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.122423] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.153053] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.183432] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.212335] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.241849] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.273112] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.302898] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.333098] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.369519] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.403782] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.436104] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.465838] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.493393] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.520742] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.555715] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.583246] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.611107] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.640971] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.669934] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.709583] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.750085] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.781678] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.820249] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.849863] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.878379] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.905814] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.938447] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1371.976597] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.003959] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.034551] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.063075] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.090915] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.118074] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.145352] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.172688] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.199214] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.226755] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.254669] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.281286] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.308892] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.337289] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.373312] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.406347] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.433794] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.462399] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.491638] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.521773] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.551733] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.580246] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.607942] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.634988] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.661030] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.687198] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.716783] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.747365] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.777220] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.807153] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.836201] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.863827] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.901127] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.928798] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.956583] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1372.986754] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.011943] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.039741] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.072404] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.106738] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.144690] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.173104] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.207946] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.238427] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.265853] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.299739] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.332407] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.360227] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.390144] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.419337] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.447027] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.477045] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.506535] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.535192] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.569884] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.600767] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.630864] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.661158] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.692434] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.720889] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.749052] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.779401] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.813610] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.848568] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.883419] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.917037] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.952034] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1373.982474] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.015729] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.046726] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.078557] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.109759] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.137730] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.167767] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.201174] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.230846] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.265715] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.299078] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.327776] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.369202] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.399507] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.428988] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.456571] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.484900] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.512788] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.542751] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.573197] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.603415] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.629605] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.658813] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.686326] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.715418] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.741770] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.767997] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.794766] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.821719] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.851478] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.879495] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.909095] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.937453] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.963402] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1374.988147] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.015535] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.039779] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.065123] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.092585] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.122112] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.151604] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.181702] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.212709] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.240937] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.269822] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.299371] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.329867] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.360740] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.389736] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.416461] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.445084] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.472474] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.501994] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.533757] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.562129] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.589881] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.619623] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.647635] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.676915] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.704635] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.735419] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.772156] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.800475] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.828764] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.860246] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.891739] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.921406] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.951499] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1375.983916] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.008847] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.037236] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.066935] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.096613] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.128722] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.163675] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.199481] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.230766] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.260979] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.293917] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.330706] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.363753] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.393982] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.422921] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.452488] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.479771] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.508534] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.542581] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.569283] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.607581] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.639429] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.680320] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.715149] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.750066] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.779359] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.810923] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.842344] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.876507] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.909147] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.950582] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1376.983619] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.021429] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.054196] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.091754] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.121268] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.151358] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.204069] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.239990] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.273749] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.314113] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.345294] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.385362] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.418923] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.451719] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.486614] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.522504] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.563722] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.613052] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.652362] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.704511] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.764644] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.803988] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.852690] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.899923] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.939912] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1377.984366] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.031774] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.072117] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.110879] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.143669] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.172536] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.205279] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.244960] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.283604] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.314790] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.354360] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.394937] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.429504] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.461358] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.508177] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.558191] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.601293] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.637774] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.672240] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.711593] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.749992] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.782632] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.828717] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.870593] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.918198] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.948695] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1378.979244] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.016783] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.052136] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.096765] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.128650] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.159095] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.203905] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.250170] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.286993] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.316893] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.357642] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.409222] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.453882] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.491293] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.534374] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.572683] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.606090] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.640778] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.684962] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.721993] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.767576] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.810132] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.841563] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.888853] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.923414] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1379.961563] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.005349] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.045593] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.102820] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.141447] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.185417] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.233547] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.273691] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.308151] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.344571] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.383952] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.422339] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.451259] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.487401] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.536310] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.583428] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.623156] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.665425] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.699924] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.735123] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.774861] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.817109] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.853937] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.906428] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1380.962104] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.014715] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.056659] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.098772] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.132462] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.172060] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.203406] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.236695] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.270877] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.316188] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.356257] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.397895] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.443564] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.500125] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.539374] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.583654] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.626861] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.673680] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.714221] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.757679] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.795377] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.826357] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.856020] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.890995] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.924260] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1381.973359] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.023161] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.074793] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.117338] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.149707] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.186135] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.223730] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.261283] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.291549] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.322432] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.354386] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.391973] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.426448] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.462809] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.501191] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.545293] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.581233] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.617271] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.661560] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.712161] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.752936] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.795687] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.836214] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.868302] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.915619] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.955297] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1382.989255] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.036695] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.074507] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.114759] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.150746] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.189628] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.230908] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.266398] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.309089] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.354072] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.396247] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.435857] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.470638] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.518890] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.572793] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.622271] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.665745] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.716969] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.756438] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.811458] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.864073] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.913894] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1383.956623] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.006926] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.043021] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.083405] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.124511] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.165769] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.214161] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.247853] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.286273] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.326775] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.361844] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.413945] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.460748] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.506051] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.551958] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.603963] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.645442] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.679077] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.727160] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.758351] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.800266] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.846052] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.879496] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.915634] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.961318] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1384.991057] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.027385] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.062318] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.108094] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.153222] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.198091] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.240995] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.271621] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.304214] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.348061] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.384965] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.421526] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.470032] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.527607] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.582625] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.645830] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.684907] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.718526] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.749544] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.779327] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.809569] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.839405] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.876343] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.916812] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1385.972982] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.014800] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.049459] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.083190] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.118483] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.146311] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.179116] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.209620] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.246965] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.288162] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.321844] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.350856] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.383178] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.417949] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.452445] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.486286] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.515384] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.554411] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.584981] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.626276] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.662777] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.705699] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.745589] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.783244] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.816989] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.854444] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.890802] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.921037] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.957888] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1386.993716] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.033805] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.066297] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.102395] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.157988] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.195351] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.229621] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.264855] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.304832] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.341560] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.382061] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.419074] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.457236] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.487468] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.526073] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.560298] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.593355] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.627613] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.660989] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.704275] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.752300] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.796286] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.830486] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.871874] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.910062] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.943144] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1387.980374] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.042642] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.080369] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.127585] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.175940] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.210869] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.250188] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.291237] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.325947] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.363436] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.408288] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.444209] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.474870] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.506151] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.548102] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.588097] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.625628] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.680281] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.714047] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.745360] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.790602] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.840268] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.876348] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.917515] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.950317] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1388.994833] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.025566] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.061623] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.098618] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.133349] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.170926] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.212729] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.246066] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.278726] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.315848] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.357262] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.396716] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.425976] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.468535] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.501324] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1389.536258] Lustre: lustre-OST0001-osc-ffff8b1b84f7c000: disconnect after 22s idle [ 1389.538955] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 1393.720360] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 06:56:55 (1756810615) [ 1393.911844] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1393.943105] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1393.964295] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.034876] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.060895] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.128880] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.159024] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.392899] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.431286] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.577774] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.649068] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.685066] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.713841] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.789053] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.857494] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1394.937793] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.001975] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.028579] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.119966] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.148363] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.242327] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.270857] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.374847] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.414676] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.583344] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.672153] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.706576] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1395.939744] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1396.094848] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1396.120440] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1396.317705] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1396.345795] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1396.371283] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1396.461174] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1396.516988] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1396.545821] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1396.827970] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1396.906068] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1397.135795] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1397.317858] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1397.514845] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1397.608612] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1397.630476] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1397.655587] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1397.679687] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1397.794176] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.402480] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.422385] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.442577] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.459989] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.482397] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.508428] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.529255] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.562991] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.587170] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.611284] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.928391] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1398.959773] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1399.055433] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1399.077475] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1399.468747] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1399.661708] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1399.708097] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1399.730322] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1399.750627] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1399.938517] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1399.958598] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.147485] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.170863] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.193592] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.222598] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.370768] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.394740] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.415798] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.543948] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.568961] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.590318] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.609638] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.641318] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.662724] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.782885] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1400.964615] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1401.066725] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1401.090591] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1401.655948] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1401.693838] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1401.757904] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1401.964684] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1402.374135] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1402.460420] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1402.485221] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1402.506244] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1402.737166] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1402.759879] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1402.871451] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1402.896509] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1403.379710] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1403.509956] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1403.534803] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1403.932298] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1403.962735] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1403.995720] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.033178] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.060731] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.113526] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.137324] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.157742] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.178617] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.201883] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.226874] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.554799] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.575885] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.680674] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.704642] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1404.974480] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1405.725908] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1405.833420] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1405.900778] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1405.931175] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1405.958358] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.091410] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.122437] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.246894] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.278207] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.434405] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.470245] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.490810] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.512483] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.551873] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.776964] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.899589] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.926377] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.953804] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1406.975583] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1407.261167] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1407.317505] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1407.421706] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1407.670221] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1407.805677] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1407.837497] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1407.862568] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1407.889910] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1407.913894] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1407.940287] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1407.999430] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1408.185769] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1408.439285] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1408.561583] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1408.756310] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1409.275879] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1409.493256] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1409.559909] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1409.936152] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1409.972337] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1410.058123] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1410.130472] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1410.722291] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1410.782177] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1411.167937] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1411.196043] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1411.223929] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1411.345055] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1411.477651] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1411.584491] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1411.643901] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1412.045684] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1412.638372] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1412.690525] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1412.723522] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1412.759851] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1412.783253] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1412.820408] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1412.960929] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1413.140181] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1413.225392] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1413.539895] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1413.650261] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1413.675942] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1413.745657] rw_seq_cst_vs_d (32966): drop_caches: 3 [ 1415.136289] Lustre: lustre-OST0000-osc-ffff8b1b85b4c000: disconnect after 20s idle [ 1417.746428] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 06:57:19 (1756810639) [ 1421.467775] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 06:57:23 (1756810643) [ 1425.502163] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 06:57:26 (1756810646) [ 1442.257693] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 06:57:43 (1756810663) [ 1442.553361] bash (35454): drop_caches: 3 [ 1445.648844] bash (35454): drop_caches: 3 [ 1448.747131] bash (35454): drop_caches: 3 [ 1451.841545] bash (35454): drop_caches: 3 [ 1454.943320] bash (35454): drop_caches: 3 [ 1458.080315] bash (35454): drop_caches: 3 [ 1461.178241] bash (35454): drop_caches: 3 [ 1465.608432] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 06:58:06 (1756810686) [ 1466.336216] Lustre: lustre-OST0001-osc-ffff8b1b84f7c000: disconnect after 22s idle [ 1466.338973] Lustre: Skipped 1 previous similar message [ 1472.089694] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 06:58:13 (1756810693) [ 1506.283337] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 06:58:47 (1756810727) [ 1510.819268] Lustre: DEBUG MARKER: loop 5 [ 1513.366350] Lustre: DEBUG MARKER: loop 10 [ 1515.629572] Lustre: DEBUG MARKER: loop 15 [ 1518.113131] Lustre: DEBUG MARKER: loop 20 [ 1522.537362] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 06:59:03 (1756810743) [ 1522.658214] Lustre: lustre-OST0000-osc-ffff8b1b85b4c000: disconnect after 22s idle [ 1526.542524] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 06:59:08 (1756810748) [ 1530.497112] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 06:59:11 (1756810751) [ 1543.136552] Lustre: lustre-OST0000-osc-ffff8b1b85b4c000: disconnect after 20s idle [ 1543.143788] Lustre: Skipped 1 previous similar message [ 1596.332628] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 07:00:17 (1756810817) [ 1599.912882] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 07:00:21 (1756810821) [ 1603.017632] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 07:00:24 (1756810824) [ 1606.321108] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 07:00:27 (1756810827) [ 1609.385119] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 07:00:31 (1756810831) [ 1613.342766] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 07:00:34 (1756810834) [ 1618.253671] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 07:00:39 (1756810839) [ 1622.183325] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1623.050585] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 07:00:44 (1756810844) [ 1628.255756] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 07:00:49 (1756810849) [ 1628.469764] Lustre: *** cfs_fail_loc=314, val=0*** [ 1629.536398] Lustre: *** cfs_fail_loc=314, val=0*** [ 1629.540162] Lustre: Skipped 2 previous similar messages [ 1632.629369] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 07:00:54 (1756810854) [ 1637.980414] Lustre: *** cfs_fail_loc=314, val=0*** [ 1640.426821] Lustre: lustre-OST0000-osc-ffff8b1b84f7c000: Connection to lustre-OST0000 (at 192.168.202.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1640.437809] LustreError: lustre-OST0000-osc-ffff8b1b84f7c000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1640.443980] Lustre: 2418:0:(llite_lib.c:4239:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.137@tcp:/lustre/fid: [0x240000403:0x1:0x0]// may get corrupted (rc -108) [ 1640.451568] Lustre: lustre-OST0000-osc-ffff8b1b84f7c000: Connection restored to 192.168.202.137@tcp (at 192.168.202.137@tcp) [ 1641.381552] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 07:01:02 (1756810862) [ 1641.484713] LustreError: 46949:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1644.504216] LustreError: 46949:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1647.197851] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 07:01:08 (1756810868) [ 1650.960329] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 07:01:12 (1756810872) [ 1655.489662] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1656.516669] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 07:01:17 (1756810877) [ 1657.531951] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1658.478421] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 07:01:19 (1756810879) [ 1660.904443] Lustre: lustre-MDT0000-mdc-ffff8b1b85b4c000: Connection to lustre-MDT0000 (at 192.168.202.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1666.021916] LustreError: MGC192.168.202.137@tcp: Connection to MGS (at 192.168.202.137@tcp) was lost; in progress operations using this service will fail [ 1666.032521] Lustre: Evicted from MGS (at 192.168.202.137@tcp) after server handle changed from 0xef664975782fb46 to 0xef664975798da43 [ 1666.036849] Lustre: MGC192.168.202.137@tcp: Connection restored to 192.168.202.137@tcp (at 192.168.202.137@tcp) [ 1669.754864] Lustre: lustre-MDT0000-mdc-ffff8b1b85b4c000: Connection restored to 192.168.202.137@tcp (at 192.168.202.137@tcp) [ 1682.582129] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 07:01:44 (1756810904) [ 1701.788705] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 07:02:03 (1756810923) [ 1701.856320] Lustre: lustre-OST0000-osc-ffff8b1b84f7c000: disconnect after 23s idle [ 1701.858760] Lustre: Skipped 3 previous similar messages [ 1709.800667] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 07:02:11 (1756810931) [ 1762.246686] Lustre: lustre-OST0000-osc-ffff8b1b85b4c000: Connection to lustre-OST0000 (at 192.168.202.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1762.254133] Lustre: Skipped 1 previous similar message [ 1762.267628] LustreError: lustre-OST0000-osc-ffff8b1b85b4c000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1762.274400] LustreError: lustre-OST0000-osc-ffff8b1b84f7c000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1762.276875] Lustre: lustre-OST0000-osc-ffff8b1b85b4c000: Connection restored to 192.168.202.137@tcp (at 192.168.202.137@tcp) [ 1762.285487] Lustre: Skipped 2 previous similar messages [ 1772.484767] Lustre: lustre-OST0001-osc-ffff8b1b85b4c000: Connection to lustre-OST0001 (at 192.168.202.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1772.490957] Lustre: Skipped 1 previous similar message [ 1772.496079] LustreError: lustre-OST0001-osc-ffff8b1b85b4c000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1772.502212] Lustre: lustre-OST0001-osc-ffff8b1b85b4c000: Connection restored to 192.168.202.137@tcp (at 192.168.202.137@tcp) [ 1783.776216] Lustre: lustre-OST0000-osc-ffff8b1b85b4c000: disconnect after 22s idle [ 1786.539841] Lustre: DEBUG MARKER: oleg237-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8b1b84f7c000.ost_server_uuid 50 [ 1787.180689] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8b1b84f7c000.ost_server_uuid in IDLE state after 0 sec [ 1788.692175] Lustre: DEBUG MARKER: oleg237-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8b1b84f7c000.ost_server_uuid 50 [ 1789.294543] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8b1b84f7c000.ost_server_uuid in FULL state after 0 sec [ 1791.322079] Lustre: DEBUG MARKER: oleg237-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8b1b84f7c000.ost_server_uuid 50 [ 1791.945421] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8b1b84f7c000.ost_server_uuid in IDLE state after 0 sec [ 1793.383711] Lustre: DEBUG MARKER: oleg237-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8b1b84f7c000.ost_server_uuid 50 [ 1793.997355] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8b1b84f7c000.ost_server_uuid in FULL state after 0 sec [ 1798.166586] Lustre: DEBUG MARKER: oleg237-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8b1b84f7c000.ost_server_uuid 50 [ 1798.863512] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8b1b84f7c000.ost_server_uuid in IDLE state after 0 sec [ 1800.415131] Lustre: DEBUG MARKER: oleg237-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8b1b84f7c000.ost_server_uuid 50 [ 1801.042366] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8b1b84f7c000.ost_server_uuid in FULL state after 0 sec [ 1801.720105] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 07:03:43 (1756811023) [ 1802.891862] Lustre: DEBUG MARKER: Race attempt 0 [ 1804.670619] Lustre: DEBUG MARKER: Wait for 57511 57625 for 60 sec... [ 1867.403577] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 07:04:49 (1756811089) [ 1872.939422] Lustre: DEBUG MARKER: start test - cycle (0) [ 1891.192165] Lustre: DEBUG MARKER: start test - cycle (1) [ 1906.470596] Lustre: DEBUG MARKER: start test - cycle (2) [ 1921.760836] Lustre: DEBUG MARKER: start test - cycle (3) [ 1936.242259] Lustre: DEBUG MARKER: start test - cycle (4) [ 1952.313424] Lustre: DEBUG MARKER: start test - cycle (5) [ 1967.176442] Lustre: DEBUG MARKER: start test - cycle (6) [ 1982.214303] Lustre: DEBUG MARKER: start test - cycle (7) [ 1998.400682] Lustre: DEBUG MARKER: start test - cycle (8) [ 2013.274744] Lustre: DEBUG MARKER: start test - cycle (9) [ 2029.256054] Lustre: DEBUG MARKER: start test - cycle (10) [ 2047.449281] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 07:07:49 (1756811269) [ 2060.256363] Lustre: lustre-OST0000-osc-ffff8b1b84f7c000: disconnect after 24s idle [ 2060.261022] Lustre: Skipped 2 previous similar messages [ 2076.396383] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 07:08:18 (1756811298) [ 2079.216923] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 07:08:20 (1756811300) [ 2082.947653] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 07:08:24 (1756811304) [ 2086.781780] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 07:08:28 (1756811308) [ 2086.907234] Lustre: *** cfs_fail_loc=411, val=0*** [ 2089.386619] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2090.059125] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 07:08:31 (1756811311) [ 2099.571171] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 07:08:41 (1756811321) [ 2108.678597] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 07:08:50 (1756811330) [ 2117.775179] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 07:08:59 (1756811339) [ 2126.448252] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 07:09:08 (1756811348) [ 2132.528746] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 07:09:14 (1756811354) [ 2138.695483] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 07:09:20 (1756811360) [ 2144.790486] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 07:09:26 (1756811366) [ 2150.844123] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 07:09:32 (1756811372) [ 2156.978207] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 07:09:38 (1756811378) [ 2162.772448] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 07:09:44 (1756811384) [ 2169.181812] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 07:09:50 (1756811390) [ 2175.775757] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 07:09:57 (1756811397) [ 2193.376278] Lustre: lustre-OST0001-osc-ffff8b1b85b4c000: disconnect after 22s idle [ 2193.378857] Lustre: Skipped 4 previous similar messages [ 2797.536355] Lustre: lustre-OST0000-osc-ffff8b1b84f7c000: disconnect after 22s idle [ 2797.538827] Lustre: Skipped 1 previous similar message [ 2921.460396] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 07:22:23 (1756812143) [ 2927.454851] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 07:22:29 (1756812149) [ 2933.061463] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 07:22:34 (1756812154) [ 2938.587916] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 07:22:40 (1756812160) [ 2944.301228] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 07:22:46 (1756812166) [ 2950.107509] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 07:22:51 (1756812171) [ 2955.789778] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 07:22:57 (1756812177) [ 2961.405304] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 07:23:03 (1756812183) [ 2966.765883] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 07:23:08 (1756812188) [ 2995.600619] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 07:23:37 (1756812217) [ 3001.619850] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 07:23:43 (1756812223) [ 3007.407715] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 07:23:49 (1756812229) [ 3013.022588] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 07:23:54 (1756812234) [ 3018.629645] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 07:24:00 (1756812240) [ 3024.067837] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 07:24:05 (1756812245) [ 3029.713857] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 07:24:11 (1756812251) [ 3035.591641] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 07:24:17 (1756812257) [ 3041.230730] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 07:24:22 (1756812262) [ 3088.474582] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 07:25:10 (1756812310) [ 3567.311111] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 07:33:09 (1756812789) [ 3572.496839] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 07:33:14 (1756812794) [ 3577.834388] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 07:33:19 (1756812799) [ 3583.219504] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 07:33:24 (1756812804) [ 3588.559440] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 07:33:30 (1756812810) [ 3593.974643] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 07:33:35 (1756812815) [ 3599.371737] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 07:33:41 (1756812821) [ 3604.890494] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 07:33:46 (1756812826) [ 3610.282448] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 07:33:52 (1756812832) [ 3615.581705] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 07:33:57 (1756812837) [ 3632.096190] Lustre: lustre-OST0000-osc-ffff8b1b85b4c000: disconnect after 21s idle [ 3632.098881] Lustre: Skipped 7 previous similar messages [ 3652.947426] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 07:34:34 (1756812874) [ 3658.385607] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 07:34:40 (1756812880) [ 3663.725751] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 07:34:45 (1756812885) [ 3669.018730] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 07:34:50 (1756812890) [ 3674.221890] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 07:34:56 (1756812896) [ 3679.530112] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 07:35:01 (1756812901) [ 3684.928431] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 07:35:06 (1756812906) [ 3689.888808] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 07:35:11 (1756812911) [ 3695.220624] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 07:35:16 (1756812916) [ 4167.898925] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 07:43:09 (1756813389) [ 4173.388204] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 07:43:15 (1756813395) [ 4178.967790] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 07:43:20 (1756813400) [ 4184.474831] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 07:43:26 (1756813406) [ 4189.959377] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 07:43:31 (1756813411) [ 4195.502744] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 07:43:37 (1756813417) [ 4201.049878] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 07:43:42 (1756813422) [ 4206.672881] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 07:43:48 (1756813428) [ 4212.062854] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 07:43:53 (1756813433) [ 4217.425202] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 07:43:59 (1756813439) [ 4222.910081] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 07:44:04 (1756813444) [ 4229.273978] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 07:44:11 (1756813451) [ 4234.754778] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 07:44:16 (1756813456) [ 4240.243426] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 07:44:21 (1756813461) [ 4245.780546] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 07:44:27 (1756813467) [ 4251.126880] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 07:44:32 (1756813472) [ 4257.534183] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 07:44:39 (1756813479) [ 4257.613400] LustreError: 22645:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4259.696075] LustreError: 22645:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4264.806045] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 07:44:46 (1756813486) [ 4269.026786] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 07:44:50 (1756813490) [ 4269.120309] LustreError: 285248:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4273.176114] LustreError: 285248:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4273.182635] LustreError: 285248:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4277.240138] LustreError: 285248:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4277.254737] LustreError: 285255:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4281.312135] LustreError: 285255:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4283.550869] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 07:45:05 (1756813505) [ 4290.279908] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 07:45:12 (1756813512) [ 4293.439417] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 07:45:15 (1756813515) [ 4297.481576] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 07:45:19 (1756813519) [ 4302.816183] Lustre: lustre-OST0000-osc-ffff8b1b85b4c000: disconnect after 20s idle [ 4302.817913] Lustre: Skipped 1 previous similar message [ 4321.595361] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 07:45:43 (1756813543) [ 4329.221358] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 07:45:50 (1756813550) [ 4336.983317] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 07:45:58 (1756813558) [ 4349.930517] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 07:46:11 (1756813571) [ 4359.494551] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 07:46:21 (1756813581) [ 4372.504950] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 07:46:34 (1756813594) [ 4385.439557] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 07:46:47 (1756813607) [ 4399.729775] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 07:47:01 (1756813621) [ 4407.154380] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 07:47:08 (1756813628) [ 4437.531243] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 07:47:39 (1756813659) [ 4439.828187] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4442.546570] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 07:47:44 (1756813664) [ 4444.997188] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 07:47:46 (1756813666) [ 4447.308480] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 07:47:49 (1756813669) [ 4449.636759] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 07:47:51 (1756813671) [ 4459.892206] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 07:48:01 (1756813681) [ 4475.570789] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 07:48:17 (1756813697) [ 4477.995674] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 07:48:19 (1756813699) [ 4480.286406] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 07:48:22 (1756813702) [ 4483.368616] LustreError: lustre-MDT0000-mdc-ffff8b1b84f7c000: operation ldlm_enqueue to node 192.168.202.137@tcp failed: rc = -35 [ 4486.594465] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 07:48:28 (1756813708) [ 4486.764717] LustreError: 2420:0:(osc_request.c:3108:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4488.800198] LustreError: 2420:0:(osc_request.c:3108:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 4493.939123] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 07:48:35 (1756813715) [ 4540.217902] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 07:49:21 (1756813761) [ 4543.513404] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 07:49:25 (1756813765) [ 4547.886492] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 07:49:29 (1756813769) [ 4553.003497] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 07:49:34 (1756813774) [ 4558.159091] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 07:49:39 (1756813779) [ 4566.265622] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 07:49:47 (1756813787) [ 4574.217875] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 07:49:56 (1756813796) [ 4577.895552] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 07:49:59 (1756813799) [ 4581.849276] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 07:50:03 (1756813803) [ 4589.559255] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 07:50:11 (1756813811) [ 4630.970962] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 07:50:52 (1756813852) [ 4749.279389] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 07:52:51 (1756813971) [ 4866.537286] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 07:54:48 (1756814088) [ 4917.216201] Lustre: lustre-OST0001-osc-ffff8b1b85b4c000: disconnect after 24s idle [ 4917.218147] Lustre: Skipped 9 previous similar messages [ 5144.117354] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 07:59:25 (1756814365) [ 5174.897244] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 07:59:56 (1756814396) [ 5196.205426] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 08:00:17 (1756814417) [ 5216.846232] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 08:00:38 (1756814438) [ 5277.153358] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 08:01:38 (1756814498) [ 5333.110681] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 08:02:34 (1756814554) [ 5426.017585] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 08:04:07 (1756814647) [ 5429.582897] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 08:04:11 (1756814651) [ 5480.387769] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 08:05:02 (1756814702) [ 5521.376217] Lustre: lustre-OST0001-osc-ffff8b1b85b4c000: disconnect after 20s idle [ 5521.378161] Lustre: Skipped 11 previous similar messages [ 5526.300382] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 08:05:48 (1756814748) [ 5530.232208] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 08:05:51 (1756814751) [ 5569.672276] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 08:06:31 (1756814791) [ 5581.200889] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 08:06:42 (1756814802) [ 5622.170597] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 08:07:23 (1756814843) [ 5625.295204] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 08:07:27 (1756814847) [ 5638.108418] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 08:07:39 (1756814859) [ 5641.845918] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 08:07:43 (1756814863) [ 5642.255401] LustreError: 348569:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xc48:0x0]: rc = -5 [ 5642.258290] LustreError: 348569:0:(llite_lib.c:3794:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5642.803419] LustreError: 348621:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0x9a4:0x0]: rc = -5 [ 5642.809033] LustreError: 348621:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 9 previous similar messages [ 5642.813486] LustreError: 348621:0:(llite_lib.c:3794:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5642.818019] LustreError: 348621:0:(llite_lib.c:3794:ll_prep_inode()) Skipped 9 previous similar messages [ 5743.738472] LustreError: 348653:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xc55:0x0]: rc = -5 [ 5743.743154] LustreError: 348653:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 5 previous similar messages [ 5743.745921] LustreError: 348653:0:(llite_lib.c:3794:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5743.748655] LustreError: 348653:0:(llite_lib.c:3794:ll_prep_inode()) Skipped 5 previous similar messages [ 5743.755945] LustreError: lustre-MDT0000-mdc-ffff8b1b85b4c000: operation mds_getattr_lock to node 192.168.202.137@tcp failed: rc = -107 [ 5743.759691] Lustre: lustre-MDT0000-mdc-ffff8b1b85b4c000: Connection to lustre-MDT0000 (at 192.168.202.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5743.765345] LustreError: lustre-MDT0000-mdc-ffff8b1b85b4c000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5743.770759] LustreError: 348651:0:(file.c:6077:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 5743.771981] Lustre: lustre-MDT0000-mdc-ffff8b1b85b4c000: Connection restored to 192.168.202.137@tcp (at 192.168.202.137@tcp) [ 5746.472645] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 08:09:28 (1756814968) [ 5748.802936] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 08:09:30 (1756814970) [ 5796.282619] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 08:10:18 (1756815018) [ 5796.829751] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5797.438779] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 08:10:19 (1756815019) [ 5839.225535] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 08:11:00 (1756815060) [ 5841.609104] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 08:11:03 (1756815063) [ 5963.837620] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 08:13:05 (1756815185) [ 5971.181468] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 08:13:12 (1756815192) [ 5973.770889] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 08:13:15 (1756815195) [ 6155.863373] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 08:16:17 (1756815377) [ 6268.896110] Lustre: lustre-OST0000-osc-ffff8b1b85b4c000: disconnect after 20s idle [ 6268.898348] Lustre: Skipped 1 previous similar message [ 6338.036311] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 08:19:19 (1756815559) [ 6339.976892] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 08:19:21 (1756815561) [ 6348.693435] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 08:19:30 (1756815570) [ 6348.740197] Lustre: DEBUG MARKER: write [ 6348.762326] LustreError: 6644:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6350.764608] Lustre: DEBUG MARKER: kill 411749 [ 6350.766388] LustreError: 411749:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6353.864086] LustreError: 6644:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 6356.800078] LustreError: 411749:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 6358.733641] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 08:19:40 (1756815580) [ 6360.887672] LustreError: 412363:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 6370.976166] LustreError: 412363:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [ 6373.176225] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 08:19:54 (1756815594) [ 6373.268657] LustreError: 412950:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6375.352086] LustreError: 412950:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout interrupted [ 6381.268631] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 08:20:03 (1756815603) [ 6381.754955] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6382.344915] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 08:20:04 (1756815604) [ 6384.602646] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 08:20:06 (1756815606) [ 6386.626499] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 08:20:08 (1756815608) [ 6388.630216] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 08:20:10 (1756815610) [ 6390.580864] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 08:20:12 (1756815612) [ 6392.580775] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 08:20:14 (1756815614) [ 6394.574639] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 08:20:16 (1756815616) [ 6397.566763] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 08:20:19 (1756815619) [ 6400.095769] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 08:20:21 (1756815621) [ 6400.710255] Lustre: *** cfs_fail_loc=415, val=0*** [ 6407.065847] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 08:20:28 (1756815628) [ 6425.778244] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 08:20:47 (1756815647) [ 6425.874534] LustreError: 6169:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6425.876455] LustreError: 6169:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 6430.968077] LustreError: 15619:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6430.972129] LustreError: 15619:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 6441.160078] LustreError: 6644:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6441.161949] LustreError: 6644:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 6443.165358] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 08:21:04 (1756815664) [ 6445.417094] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 08:21:07 (1756815667) [ 6447.502343] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 08:21:09 (1756815669) [ 6449.539655] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 08:21:11 (1756815671) [ 6453.776272] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 08:21:15 (1756815675) [ 6461.875562] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 08:21:23 (1756815683) [ 6461.989476] LustreError: 423678:0:(osc_request.c:2959:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6461.991571] LustreError: 423678:0:(osc_request.c:2959:osc_build_rpc()) Skipped 6 previous similar messages [ 6466.048087] LustreError: 423678:0:(osc_request.c:2959:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6466.051363] LustreError: 423678:0:(osc_request.c:2959:osc_build_rpc()) Skipped 2 previous similar messages [ 6468.091305] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 08:21:29 (1756815689) [ 6469.191588] LustreError: 424386:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b85b4c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6469.197867] LustreError: 424386:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6469.220089] Lustre: Unmounted lustre-client [ 6469.957590] LustreError: 424406:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b84f7c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6469.960276] LustreError: 424406:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 6469.963413] LustreError: 424406:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6469.965784] LustreError: 424406:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6469.992754] Lustre: Unmounted lustre-client [ 6470.455745] Lustre: DEBUG MARKER: Iteration 1 [ 6470.613578] LustreError: 424570:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6470.613791] LustreError: 424569:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6470.619326] LustreError: 424570:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6470.660196] Lustre: Mounted lustre-client [ 6471.079983] LustreError: 424672:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ba9d21800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6471.081628] LustreError: 424673:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6471.082751] LustreError: 424672:0:(lov_obd.c:784:lov_cleanup()) Skipped 3 previous similar messages [ 6471.084225] LustreError: 424673:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6471.111042] Lustre: Unmounted lustre-client [ 6472.005187] Key type lgssc unregistered [ 6472.118406] LNet: 424915:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6472.120394] LNetError: 424915:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6472.129869] LNet: Removed LNI 192.168.202.37@tcp [ 6472.404088] Key type .llcrypt unregistered [ 6472.405503] Key type ._llcrypt unregistered [ 6472.722099] Key type ._llcrypt registered [ 6472.736036] Key type .llcrypt registered [ 6472.944492] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6472.949116] alg: No test for adler32 (adler32-zlib) [ 6473.914247] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6474.182148] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6475.792091] Key type lgssc registered [ 6476.246253] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6479.925320] Lustre: DEBUG MARKER: Iteration 2 [ 6480.033069] LustreError: 425745:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6480.033130] LustreError: 425746:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6480.037333] LustreError: 425745:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6481.090678] Lustre: Mounted lustre-client [ 6481.092243] Lustre: Skipped 1 previous similar message [ 6481.497214] LustreError: 425847:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1baf09f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6481.500429] LustreError: 425847:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6481.501064] LustreError: 425848:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6481.538664] Lustre: Unmounted lustre-client [ 6482.396342] Key type lgssc unregistered [ 6482.509438] LNet: 426090:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6482.512224] LNetError: 426090:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6482.521895] LNet: Removed LNI 192.168.202.37@tcp [ 6482.733164] Key type .llcrypt unregistered [ 6482.734710] Key type ._llcrypt unregistered [ 6482.948129] Key type ._llcrypt registered [ 6482.949040] Key type .llcrypt registered [ 6483.155661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6483.160085] alg: No test for adler32 (adler32-zlib) [ 6484.021105] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6484.095621] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6485.680142] Key type lgssc registered [ 6486.023827] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6489.248269] Lustre: DEBUG MARKER: Iteration 3 [ 6489.356280] LustreError: 426919:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6489.356322] LustreError: 426918:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6489.360486] LustreError: 426919:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6490.419592] Lustre: Mounted lustre-client [ 6490.912634] LustreError: 427020:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b88074800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6490.915676] LustreError: 427021:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6490.916304] LustreError: 427020:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6490.957436] Lustre: Unmounted lustre-client [ 6491.836383] Key type lgssc unregistered [ 6491.948341] LNet: 427263:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6491.952197] LNetError: 427263:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6491.960760] LNet: Removed LNI 192.168.202.37@tcp [ 6492.181096] Key type .llcrypt unregistered [ 6492.181972] Key type ._llcrypt unregistered [ 6492.415486] Key type ._llcrypt registered [ 6492.416368] Key type .llcrypt registered [ 6492.585823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6492.591608] alg: No test for adler32 (adler32-zlib) [ 6493.444849] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6493.515479] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6495.104108] Key type lgssc registered [ 6495.444350] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6498.650788] Lustre: DEBUG MARKER: Iteration 4 [ 6498.757166] LustreError: 428092:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6498.757455] LustreError: 428091:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6498.760651] LustreError: 428092:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6499.813391] Lustre: Mounted lustre-client [ 6500.234540] LustreError: 428194:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b898b7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6500.237705] LustreError: 428195:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6500.238458] LustreError: 428194:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6500.242676] LustreError: 428195:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6500.289573] Lustre: Unmounted lustre-client [ 6501.235777] Key type lgssc unregistered [ 6501.348445] LNet: 428437:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6501.350326] LNetError: 428437:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6501.359811] LNet: Removed LNI 192.168.202.37@tcp [ 6501.576077] Key type .llcrypt unregistered [ 6501.577036] Key type ._llcrypt unregistered [ 6501.812325] Key type ._llcrypt registered [ 6501.813214] Key type .llcrypt registered [ 6502.000754] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6502.005931] alg: No test for adler32 (adler32-zlib) [ 6502.847417] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6502.920862] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6504.496119] Key type lgssc registered [ 6504.824228] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6507.942553] Lustre: DEBUG MARKER: Iteration 5 [ 6508.041539] LustreError: 429266:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6508.041578] LustreError: 429267:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6508.045081] LustreError: 429266:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6509.098088] Lustre: Mounted lustre-client [ 6509.515881] LustreError: 429371:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ae873f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6509.520388] LustreError: 429371:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6509.523058] LustreError: 429372:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6509.526058] LustreError: 429372:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6509.558670] Lustre: Unmounted lustre-client [ 6510.441999] Key type lgssc unregistered [ 6510.559532] LNet: 429614:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6510.561658] LNetError: 429614:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6510.568699] LNet: Removed LNI 192.168.202.37@tcp [ 6510.811100] Key type .llcrypt unregistered [ 6510.812044] Key type ._llcrypt unregistered [ 6511.055071] Key type ._llcrypt registered [ 6511.056697] Key type .llcrypt registered [ 6511.227831] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6511.232270] alg: No test for adler32 (adler32-zlib) [ 6512.073116] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6512.146038] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6513.720168] Key type lgssc registered [ 6514.057445] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6517.211447] Lustre: DEBUG MARKER: Iteration 6 [ 6517.308491] LustreError: 430442:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6517.309068] LustreError: 430443:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6517.312560] LustreError: 430442:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6518.356027] Lustre: Mounted lustre-client [ 6518.790079] LustreError: 430544:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b86d68000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6518.793599] LustreError: 430544:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6518.794428] LustreError: 430545:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6518.823161] Lustre: Unmounted lustre-client [ 6519.718926] Key type lgssc unregistered [ 6519.838339] LNet: 430788:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6519.840270] LNetError: 430788:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6519.846715] LNet: Removed LNI 192.168.202.37@tcp [ 6520.078093] Key type .llcrypt unregistered [ 6520.078975] Key type ._llcrypt unregistered [ 6520.315720] Key type ._llcrypt registered [ 6520.318640] Key type .llcrypt registered [ 6520.501341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6520.506146] alg: No test for adler32 (adler32-zlib) [ 6521.359875] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6521.437467] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6523.016095] Key type lgssc registered [ 6523.363239] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6526.573925] Lustre: DEBUG MARKER: Iteration 7 [ 6526.676084] LustreError: 431616:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6526.678139] LustreError: 431617:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6526.679812] LustreError: 431616:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 6527.723626] Lustre: Mounted lustre-client [ 6528.151817] LustreError: 431719:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1bb05b9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6528.153465] LustreError: 431720:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6528.154377] LustreError: 431719:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6528.211061] Lustre: Unmounted lustre-client [ 6529.129693] Key type lgssc unregistered [ 6529.249303] LNet: 431963:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6529.251785] LNetError: 431963:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6529.259480] LNet: Removed LNI 192.168.202.37@tcp [ 6529.475091] Key type .llcrypt unregistered [ 6529.476883] Key type ._llcrypt unregistered [ 6529.712960] Key type ._llcrypt registered [ 6529.714412] Key type .llcrypt registered [ 6529.906496] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6529.911047] alg: No test for adler32 (adler32-zlib) [ 6530.764153] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6530.836484] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6532.416105] Key type lgssc registered [ 6532.742676] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6535.934311] Lustre: DEBUG MARKER: Iteration 8 [ 6536.031366] LustreError: 432791:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6536.032305] LustreError: 432792:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6536.034993] LustreError: 432791:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6537.089773] Lustre: Mounted lustre-client [ 6537.510312] LustreError: 432897:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b990f0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6537.514070] LustreError: 432897:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6537.514965] LustreError: 432898:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6537.621883] Lustre: Unmounted lustre-client [ 6537.623305] Lustre: Skipped 1 previous similar message [ 6538.517106] Key type lgssc unregistered [ 6538.635456] LNet: 433141:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6538.637566] LNetError: 433141:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6538.644758] LNet: Removed LNI 192.168.202.37@tcp [ 6538.857184] Key type .llcrypt unregistered [ 6538.858159] Key type ._llcrypt unregistered [ 6539.091850] Key type ._llcrypt registered [ 6539.092716] Key type .llcrypt registered [ 6539.268037] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6539.272257] alg: No test for adler32 (adler32-zlib) [ 6540.115658] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6540.191170] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6541.768144] Key type lgssc registered [ 6542.124296] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6545.317270] Lustre: DEBUG MARKER: Iteration 9 [ 6545.411232] LustreError: 433969:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6545.411275] LustreError: 433970:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6545.416816] LustreError: 433969:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6546.468552] Lustre: Mounted lustre-client [ 6546.470015] Lustre: Skipped 1 previous similar message [ 6546.897617] LustreError: 434072:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b83cf9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6546.899654] LustreError: 434073:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6546.901210] LustreError: 434072:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6546.958107] Lustre: Unmounted lustre-client [ 6547.865319] Key type lgssc unregistered [ 6547.978536] LNet: 434316:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6547.980470] LNetError: 434316:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6547.987761] LNet: Removed LNI 192.168.202.37@tcp [ 6548.230123] Key type .llcrypt unregistered [ 6548.231047] Key type ._llcrypt unregistered [ 6548.515490] Key type ._llcrypt registered [ 6548.529040] Key type .llcrypt registered [ 6548.683727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6548.689517] alg: No test for adler32 (adler32-zlib) [ 6549.530541] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6549.606118] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6551.184125] Key type lgssc registered [ 6551.517582] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6554.688705] Lustre: DEBUG MARKER: Iteration 10 [ 6554.787230] LustreError: 435144:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6554.787500] LustreError: 435145:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6554.790850] LustreError: 435144:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6555.840956] Lustre: Mounted lustre-client [ 6555.842044] Lustre: Skipped 1 previous similar message [ 6556.274168] LustreError: 435246:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b98cc6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6556.276504] LustreError: 435247:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6556.277487] LustreError: 435246:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6556.328470] Lustre: Unmounted lustre-client [ 6557.244171] Key type lgssc unregistered [ 6557.356497] LNet: 435489:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6557.358453] LNetError: 435489:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6557.364674] LNet: Removed LNI 192.168.202.37@tcp [ 6557.582086] Key type .llcrypt unregistered [ 6557.583072] Key type ._llcrypt unregistered [ 6557.823442] Key type ._llcrypt registered [ 6557.824573] Key type .llcrypt registered [ 6557.997199] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6558.002139] alg: No test for adler32 (adler32-zlib) [ 6558.850386] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6558.927381] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6560.504093] Key type lgssc registered [ 6560.826480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6563.992508] Lustre: DEBUG MARKER: Iteration 11 [ 6564.122294] LustreError: 436318:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6564.122365] LustreError: 436319:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6564.126226] LustreError: 436318:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6565.184156] Lustre: Mounted lustre-client [ 6565.185663] Lustre: Skipped 1 previous similar message [ 6565.603249] LustreError: 436423:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b871c1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6565.605224] LustreError: 436422:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6565.605980] LustreError: 436423:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6565.667063] Lustre: Unmounted lustre-client [ 6566.571761] Key type lgssc unregistered [ 6566.700507] LNet: 436663:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6566.703673] LNetError: 436663:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6566.715683] LNet: Removed LNI 192.168.202.37@tcp [ 6566.968098] Key type .llcrypt unregistered [ 6566.968948] Key type ._llcrypt unregistered [ 6567.249428] Key type ._llcrypt registered [ 6567.250769] Key type .llcrypt registered [ 6567.407341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6567.411547] alg: No test for adler32 (adler32-zlib) [ 6568.277863] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6568.362334] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6569.952151] Key type lgssc registered [ 6570.408552] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6573.838838] Lustre: DEBUG MARKER: Iteration 12 [ 6573.930811] LustreError: 437491:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6573.931231] LustreError: 437492:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6573.934344] LustreError: 437491:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6574.992344] Lustre: Mounted lustre-client [ 6574.992344] Lustre: Mounted lustre-client [ 6575.406469] LustreError: 437595:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ae873d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6575.409049] LustreError: 437595:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6575.409726] LustreError: 437594:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6575.514625] Lustre: Unmounted lustre-client [ 6576.402462] Key type lgssc unregistered [ 6576.514484] LNet: 437836:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6576.516364] LNetError: 437836:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6576.523749] LNet: Removed LNI 192.168.202.37@tcp [ 6576.739163] Key type .llcrypt unregistered [ 6576.740096] Key type ._llcrypt unregistered [ 6576.968168] Key type ._llcrypt registered [ 6576.969079] Key type .llcrypt registered [ 6577.185701] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6577.190314] alg: No test for adler32 (adler32-zlib) [ 6578.035291] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6578.116254] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6579.704100] Key type lgssc registered [ 6580.024340] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6583.253736] Lustre: DEBUG MARKER: Iteration 13 [ 6583.354357] LustreError: 438664:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6583.354403] LustreError: 438665:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6583.360705] LustreError: 438664:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6584.416542] Lustre: Mounted lustre-client [ 6584.837552] LustreError: 438766:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b9fefe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6584.841132] LustreError: 438767:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6584.843035] LustreError: 438766:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6584.894487] Lustre: Unmounted lustre-client [ 6585.843812] Key type lgssc unregistered [ 6585.953494] LNet: 439008:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6585.955785] LNetError: 439008:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6585.964728] LNet: Removed LNI 192.168.202.37@tcp [ 6586.205089] Key type .llcrypt unregistered [ 6586.205994] Key type ._llcrypt unregistered [ 6586.430666] Key type ._llcrypt registered [ 6586.431834] Key type .llcrypt registered [ 6586.634425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6586.639120] alg: No test for adler32 (adler32-zlib) [ 6587.485129] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6587.559570] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6589.136116] Key type lgssc registered [ 6589.482874] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6592.743942] Lustre: DEBUG MARKER: Iteration 14 [ 6592.842881] LustreError: 439837:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6592.842924] LustreError: 439836:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6592.847752] LustreError: 439837:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6593.899974] Lustre: Mounted lustre-client [ 6593.901593] Lustre: Skipped 1 previous similar message [ 6594.322601] LustreError: 439941:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b85396800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6594.324481] LustreError: 439940:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6594.325630] LustreError: 439941:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6594.362643] Lustre: Unmounted lustre-client [ 6595.261493] Key type lgssc unregistered [ 6595.366562] LNet: 440183:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6595.368496] LNetError: 440183:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6595.375738] LNet: Removed LNI 192.168.202.37@tcp [ 6595.591081] Key type .llcrypt unregistered [ 6595.591983] Key type ._llcrypt unregistered [ 6595.797086] Key type ._llcrypt registered [ 6595.797965] Key type .llcrypt registered [ 6595.964611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6595.973434] alg: No test for adler32 (adler32-zlib) [ 6596.819157] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6596.893580] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6598.472119] Key type lgssc registered [ 6598.807305] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6601.845234] Lustre: DEBUG MARKER: Iteration 15 [ 6601.930237] LustreError: 441010:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6601.934975] LustreError: 441012:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6601.936885] LustreError: 441010:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6602.990220] Lustre: Mounted lustre-client [ 6602.991060] Lustre: Skipped 1 previous similar message [ 6603.422173] LustreError: 441116:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ae8739000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6603.425641] LustreError: 441116:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6603.427841] LustreError: 441115:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6603.471049] Lustre: Unmounted lustre-client [ 6604.367713] Key type lgssc unregistered [ 6604.485331] LNet: 441358:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6604.487183] LNetError: 441358:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6604.496790] LNet: Removed LNI 192.168.202.37@tcp [ 6604.722079] Key type .llcrypt unregistered [ 6604.722948] Key type ._llcrypt unregistered [ 6604.924103] Key type ._llcrypt registered [ 6604.925217] Key type .llcrypt registered [ 6605.118842] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6605.122952] alg: No test for adler32 (adler32-zlib) [ 6605.977484] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6606.050736] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6607.624132] Key type lgssc registered [ 6607.987700] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6611.111485] Lustre: DEBUG MARKER: Iteration 16 [ 6611.204272] LustreError: 442184:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6611.204870] LustreError: 442187:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6611.209107] LustreError: 442184:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6612.261626] Lustre: Mounted lustre-client [ 6612.262949] Lustre: Skipped 1 previous similar message [ 6612.681447] LustreError: 442289:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b871c5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6612.683687] LustreError: 442289:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6612.686499] LustreError: 442288:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6612.688052] LustreError: 442288:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6612.739822] Lustre: Unmounted lustre-client [ 6613.595898] Key type lgssc unregistered [ 6613.713439] LNet: 442532:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6613.715246] LNetError: 442532:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6613.724886] LNet: Removed LNI 192.168.202.37@tcp [ 6613.950107] Key type .llcrypt unregistered [ 6613.950964] Key type ._llcrypt unregistered [ 6614.137099] Key type ._llcrypt registered [ 6614.138420] Key type .llcrypt registered [ 6614.349857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6614.356483] alg: No test for adler32 (adler32-zlib) [ 6615.196624] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6615.267969] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6616.840146] Key type lgssc registered [ 6617.149238] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6620.133628] Lustre: DEBUG MARKER: Iteration 17 [ 6620.233892] LustreError: 443360:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6620.235090] LustreError: 443361:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6620.237271] LustreError: 443360:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6621.287649] Lustre: Mounted lustre-client [ 6621.721559] LustreError: 443466:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b82c5c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6621.725281] LustreError: 443465:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6621.726125] LustreError: 443466:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6621.834339] Lustre: Unmounted lustre-client [ 6621.835576] Lustre: Skipped 1 previous similar message [ 6622.733624] Key type lgssc unregistered [ 6622.860518] LNet: 443708:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6622.862454] LNetError: 443708:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6622.870722] LNet: Removed LNI 192.168.202.37@tcp [ 6623.098080] Key type .llcrypt unregistered [ 6623.099487] Key type ._llcrypt unregistered [ 6623.329051] Key type ._llcrypt registered [ 6623.329914] Key type .llcrypt registered [ 6623.522543] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6623.527190] alg: No test for adler32 (adler32-zlib) [ 6624.377577] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6624.460709] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6626.040090] Key type lgssc registered [ 6626.362554] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6629.420479] Lustre: DEBUG MARKER: Iteration 18 [ 6629.514031] LustreError: 444535:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6629.514064] LustreError: 444537:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6629.518955] LustreError: 444535:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6630.572390] Lustre: Mounted lustre-client [ 6630.574067] Lustre: Skipped 1 previous similar message [ 6630.977164] LustreError: 444642:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ae873f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6630.979530] LustreError: 444643:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6630.981658] LustreError: 444642:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6631.004541] Lustre: Unmounted lustre-client [ 6631.885762] Key type lgssc unregistered [ 6631.999512] LNet: 444884:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6632.001355] LNetError: 444884:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6632.008734] LNet: Removed LNI 192.168.202.37@tcp [ 6632.236091] Key type .llcrypt unregistered [ 6632.237038] Key type ._llcrypt unregistered [ 6632.455357] Key type ._llcrypt registered [ 6632.456861] Key type .llcrypt registered [ 6632.633780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6632.646090] alg: No test for adler32 (adler32-zlib) [ 6633.490039] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6633.568020] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6635.144168] Key type lgssc registered [ 6635.481177] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6638.866486] Lustre: DEBUG MARKER: Iteration 19 [ 6638.983870] LustreError: 445711:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6638.991070] LustreError: 445714:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6638.992683] LustreError: 445711:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 6640.056805] Lustre: Mounted lustre-client [ 6640.057687] Lustre: Skipped 1 previous similar message [ 6640.600193] LustreError: 445814:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ae873c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6640.602915] LustreError: 445813:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6640.603404] LustreError: 445814:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6640.635112] Lustre: Unmounted lustre-client [ 6641.687420] Key type lgssc unregistered [ 6641.814461] LNet: 446056:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6641.818460] LNetError: 446056:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6641.829170] LNet: Removed LNI 192.168.202.37@tcp [ 6642.078137] Key type .llcrypt unregistered [ 6642.079599] Key type ._llcrypt unregistered [ 6642.467239] Key type ._llcrypt registered [ 6642.468237] Key type .llcrypt registered [ 6642.637462] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6642.642067] alg: No test for adler32 (adler32-zlib) [ 6643.496435] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6643.579119] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6645.160153] Key type lgssc registered [ 6645.535091] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6648.843742] Lustre: DEBUG MARKER: Iteration 20 [ 6648.950880] LustreError: 446885:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6648.950933] LustreError: 446886:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6648.954701] LustreError: 446885:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6650.010046] Lustre: Mounted lustre-client [ 6650.437950] LustreError: 446987:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b88072800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6650.440664] LustreError: 446987:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6650.443052] LustreError: 446988:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6650.444841] LustreError: 446988:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6650.486270] Lustre: Unmounted lustre-client [ 6651.379177] Key type lgssc unregistered [ 6651.504483] LNet: 447229:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6651.506445] LNetError: 447229:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6651.513726] LNet: Removed LNI 192.168.202.37@tcp [ 6651.758101] Key type .llcrypt unregistered [ 6651.758980] Key type ._llcrypt unregistered [ 6651.964644] Key type ._llcrypt registered [ 6651.965984] Key type .llcrypt registered [ 6652.184402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6652.190865] alg: No test for adler32 (adler32-zlib) [ 6653.042093] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6653.119396] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6654.704130] Key type lgssc registered [ 6655.070802] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6658.294200] Lustre: DEBUG MARKER: Iteration 21 [ 6658.404309] LustreError: 448058:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6658.404346] LustreError: 448059:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6658.407798] LustreError: 448058:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6659.454029] Lustre: Mounted lustre-client [ 6659.875826] LustreError: 448159:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b84d25800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6659.878569] LustreError: 448159:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6659.880347] LustreError: 448160:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6659.882296] LustreError: 448160:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6659.922183] Lustre: Unmounted lustre-client [ 6660.837681] Key type lgssc unregistered [ 6660.948468] LNet: 448404:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6660.950379] LNetError: 448404:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6660.961675] LNet: Removed LNI 192.168.202.37@tcp [ 6661.198089] Key type .llcrypt unregistered [ 6661.199276] Key type ._llcrypt unregistered [ 6661.469750] Key type ._llcrypt registered [ 6661.470715] Key type .llcrypt registered [ 6661.651413] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6661.656048] alg: No test for adler32 (adler32-zlib) [ 6662.511127] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6662.605462] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6664.184119] Key type lgssc registered [ 6664.559910] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6667.974688] Lustre: DEBUG MARKER: Iteration 22 [ 6668.078243] LustreError: 449232:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6668.079282] LustreError: 449233:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6668.083595] LustreError: 449232:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6669.138636] Lustre: Mounted lustre-client [ 6669.140291] Lustre: Skipped 1 previous similar message [ 6669.570519] LustreError: 449337:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ae873a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6669.573387] LustreError: 449337:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6669.573809] LustreError: 449338:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6669.617951] Lustre: Unmounted lustre-client [ 6670.579326] Key type lgssc unregistered [ 6670.700469] LNet: 449579:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6670.702482] LNetError: 449579:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6670.714577] LNet: Removed LNI 192.168.202.37@tcp [ 6670.985123] Key type .llcrypt unregistered [ 6670.986867] Key type ._llcrypt unregistered [ 6671.217445] Key type ._llcrypt registered [ 6671.218283] Key type .llcrypt registered [ 6671.414796] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6671.419708] alg: No test for adler32 (adler32-zlib) [ 6672.287564] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6672.367323] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6673.952174] Key type lgssc registered [ 6674.294409] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6677.626438] Lustre: DEBUG MARKER: Iteration 23 [ 6677.729053] LustreError: 450407:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6677.729135] LustreError: 450408:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6677.733377] LustreError: 450407:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6678.784542] Lustre: Mounted lustre-client [ 6679.219856] LustreError: 450510:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b871c3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6679.224131] LustreError: 450510:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6679.300109] Lustre: Unmounted lustre-client [ 6680.272945] Key type lgssc unregistered [ 6680.389496] LNet: 450751:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6680.391415] LNetError: 450751:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6680.399756] LNet: Removed LNI 192.168.202.37@tcp [ 6680.616113] Key type .llcrypt unregistered [ 6680.617025] Key type ._llcrypt unregistered [ 6680.852440] Key type ._llcrypt registered [ 6680.854234] Key type .llcrypt registered [ 6681.053909] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6681.061085] alg: No test for adler32 (adler32-zlib) [ 6681.906093] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6681.991405] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6683.576131] Key type lgssc registered [ 6683.954402] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6687.196898] Lustre: DEBUG MARKER: Iteration 24 [ 6687.290968] LustreError: 451580:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6687.291197] LustreError: 451581:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6687.295044] LustreError: 451580:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6688.343537] Lustre: Mounted lustre-client [ 6688.344453] Lustre: Skipped 1 previous similar message [ 6688.760816] LustreError: 451684:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b98cc6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6688.763975] LustreError: 451684:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6688.764789] LustreError: 451683:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6688.767491] LustreError: 451683:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6688.829103] Lustre: Unmounted lustre-client [ 6689.786379] Key type lgssc unregistered [ 6689.898418] LNet: 451926:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6689.900322] LNetError: 451926:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6689.910606] LNet: Removed LNI 192.168.202.37@tcp [ 6690.139165] Key type .llcrypt unregistered [ 6690.140480] Key type ._llcrypt unregistered [ 6690.386623] Key type ._llcrypt registered [ 6690.388203] Key type .llcrypt registered [ 6690.676955] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6690.686264] alg: No test for adler32 (adler32-zlib) [ 6691.556855] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6691.655438] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6693.240123] Key type lgssc registered [ 6693.591718] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6696.990693] Lustre: DEBUG MARKER: Iteration 25 [ 6697.089233] LustreError: 452755:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6697.089271] LustreError: 452756:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6697.094275] LustreError: 452755:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6698.150890] Lustre: Mounted lustre-client [ 6698.152444] Lustre: Skipped 1 previous similar message [ 6698.592406] LustreError: 452859:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ae873b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6698.595640] LustreError: 452859:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6698.597194] LustreError: 452860:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6698.599462] LustreError: 452860:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6698.670136] Lustre: Unmounted lustre-client [ 6698.671641] Lustre: Skipped 1 previous similar message [ 6699.627965] Key type lgssc unregistered [ 6699.755541] LNet: 453102:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6699.757741] LNetError: 453102:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6699.766086] LNet: Removed LNI 192.168.202.37@tcp [ 6700.026127] Key type .llcrypt unregistered [ 6700.027841] Key type ._llcrypt unregistered [ 6700.340993] Key type ._llcrypt registered [ 6700.345511] Key type .llcrypt registered [ 6700.643924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6700.649226] alg: No test for adler32 (adler32-zlib) [ 6701.502048] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6701.584797] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6703.168167] Key type lgssc registered [ 6703.591270] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6707.070983] Lustre: DEBUG MARKER: Iteration 26 [ 6707.188801] LustreError: 453931:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6707.189222] LustreError: 453930:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6707.193600] LustreError: 453931:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6708.257565] Lustre: Mounted lustre-client [ 6708.813135] LustreError: 454031:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ba0fd4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6708.819086] LustreError: 454031:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6708.820804] LustreError: 454031:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6708.886133] Lustre: Unmounted lustre-client [ 6709.980735] Key type lgssc unregistered [ 6710.108754] LNet: 454275:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6710.110939] LNetError: 454275:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6710.120423] LNet: Removed LNI 192.168.202.37@tcp [ 6710.386108] Key type .llcrypt unregistered [ 6710.387034] Key type ._llcrypt unregistered [ 6710.676788] Key type ._llcrypt registered [ 6710.677988] Key type .llcrypt registered [ 6710.960741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6710.965611] alg: No test for adler32 (adler32-zlib) [ 6711.844398] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6711.933345] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6713.520175] Key type lgssc registered [ 6713.962450] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6717.467360] Lustre: DEBUG MARKER: Iteration 27 [ 6717.574917] LustreError: 455104:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6717.574954] LustreError: 455105:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6717.580113] LustreError: 455104:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6718.636679] Lustre: Mounted lustre-client [ 6718.638342] Lustre: Skipped 1 previous similar message [ 6719.161888] LustreError: 455207:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1bbfba4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6719.164568] LustreError: 455206:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6719.164986] LustreError: 455207:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6719.197377] Lustre: Unmounted lustre-client [ 6720.093036] Key type lgssc unregistered [ 6720.208489] LNet: 455448:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6720.210308] LNetError: 455448:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6720.218659] LNet: Removed LNI 192.168.202.37@tcp [ 6720.440097] Key type .llcrypt unregistered [ 6720.441042] Key type ._llcrypt unregistered [ 6720.688895] Key type ._llcrypt registered [ 6720.689836] Key type .llcrypt registered [ 6720.865556] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6720.869814] alg: No test for adler32 (adler32-zlib) [ 6721.709219] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6721.781186] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6723.352109] Key type lgssc registered [ 6723.712583] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6726.944563] Lustre: DEBUG MARKER: Iteration 28 [ 6727.044741] LustreError: 456277:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6727.044773] LustreError: 456278:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6727.050636] LustreError: 456277:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6728.101583] Lustre: Mounted lustre-client [ 6728.531196] LustreError: 456382:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ba8e35800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6728.534476] LustreError: 456382:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6728.534709] LustreError: 456383:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6728.593839] Lustre: Unmounted lustre-client [ 6729.491892] Key type lgssc unregistered [ 6729.605735] LNet: 456624:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6729.607800] LNetError: 456624:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6729.617424] LNet: Removed LNI 192.168.202.37@tcp [ 6729.856101] Key type .llcrypt unregistered [ 6729.856950] Key type ._llcrypt unregistered [ 6730.131776] Key type ._llcrypt registered [ 6730.132623] Key type .llcrypt registered [ 6730.378784] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6730.383182] alg: No test for adler32 (adler32-zlib) [ 6731.237416] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6731.316227] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6732.896144] Key type lgssc registered [ 6733.263188] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6736.531342] Lustre: DEBUG MARKER: Iteration 29 [ 6736.637922] LustreError: 457452:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6736.637951] LustreError: 457453:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6736.641336] LustreError: 457452:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6737.686901] Lustre: Mounted lustre-client [ 6738.096674] LustreError: 457554:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ba0fd5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6738.101161] LustreError: 457554:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6738.148392] Lustre: Unmounted lustre-client [ 6739.020503] Key type lgssc unregistered [ 6739.132498] LNet: 457798:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6739.134410] LNetError: 457798:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6739.142772] LNet: Removed LNI 192.168.202.37@tcp [ 6739.364106] Key type .llcrypt unregistered [ 6739.365041] Key type ._llcrypt unregistered [ 6739.549400] Key type ._llcrypt registered [ 6739.550474] Key type .llcrypt registered [ 6739.739935] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6739.755524] alg: No test for adler32 (adler32-zlib) [ 6740.600214] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6740.677937] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6742.256130] Key type lgssc registered [ 6742.591894] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6745.839295] Lustre: DEBUG MARKER: Iteration 30 [ 6745.950355] LustreError: 458625:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6745.950382] LustreError: 458626:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6745.954860] LustreError: 458625:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6747.003795] Lustre: Mounted lustre-client [ 6747.433031] LustreError: 458725:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ae873b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6747.435991] LustreError: 458726:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6747.436310] LustreError: 458725:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6747.495129] Lustre: Unmounted lustre-client [ 6748.378087] Key type lgssc unregistered [ 6748.489322] LNet: 458966:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6748.491337] LNetError: 458966:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6748.497689] LNet: Removed LNI 192.168.202.37@tcp [ 6748.703090] Key type .llcrypt unregistered [ 6748.704037] Key type ._llcrypt unregistered [ 6748.980447] Key type ._llcrypt registered [ 6748.981367] Key type .llcrypt registered [ 6749.137531] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6749.142443] alg: No test for adler32 (adler32-zlib) [ 6749.992270] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6750.067117] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6751.648122] Key type lgssc registered [ 6751.996440] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6755.066992] Lustre: DEBUG MARKER: Iteration 31 [ 6755.164080] LustreError: 459796:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6755.164120] LustreError: 459797:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6755.167874] LustreError: 459796:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6756.215513] Lustre: Mounted lustre-client [ 6756.216455] Lustre: Skipped 1 previous similar message [ 6756.633185] LustreError: 459899:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b98cc4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6756.635949] LustreError: 459898:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6756.637355] LustreError: 459899:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6756.693601] Lustre: Unmounted lustre-client [ 6757.573590] Key type lgssc unregistered [ 6757.679473] LNet: 460141:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6757.681325] LNetError: 460141:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6757.690656] LNet: Removed LNI 192.168.202.37@tcp [ 6757.912085] Key type .llcrypt unregistered [ 6757.912978] Key type ._llcrypt unregistered [ 6758.139995] Key type ._llcrypt registered [ 6758.140839] Key type .llcrypt registered [ 6758.309835] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6758.314781] alg: No test for adler32 (adler32-zlib) [ 6759.154248] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6759.225570] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6760.800145] Key type lgssc registered [ 6761.140386] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6764.173164] Lustre: DEBUG MARKER: Iteration 32 [ 6764.262676] LustreError: 460969:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6764.263651] LustreError: 460970:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6764.266149] LustreError: 460969:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6765.317503] Lustre: Mounted lustre-client [ 6765.723965] LustreError: 461072:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b97375000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6765.727276] LustreError: 461072:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6765.728505] LustreError: 461073:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6765.731943] LustreError: 461073:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6765.770054] Lustre: Unmounted lustre-client [ 6766.722866] Key type lgssc unregistered [ 6766.839510] LNet: 461314:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6766.841583] LNetError: 461314:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6766.849660] LNet: Removed LNI 192.168.202.37@tcp [ 6767.091092] Key type .llcrypt unregistered [ 6767.092109] Key type ._llcrypt unregistered [ 6767.345921] Key type ._llcrypt registered [ 6767.346909] Key type .llcrypt registered [ 6767.509746] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6767.514460] alg: No test for adler32 (adler32-zlib) [ 6768.353872] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6768.425645] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6770.000103] Key type lgssc registered [ 6770.326854] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6773.457267] Lustre: DEBUG MARKER: Iteration 33 [ 6773.550665] LustreError: 462141:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6773.557381] LustreError: 462147:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6773.559206] LustreError: 462141:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 6774.615082] Lustre: Mounted lustre-client [ 6774.616704] Lustre: Skipped 1 previous similar message [ 6775.136599] LustreError: 462245:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b8669d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6775.140361] LustreError: 462246:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6775.142178] LustreError: 462245:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6775.175077] Lustre: Unmounted lustre-client [ 6776.429465] Key type lgssc unregistered [ 6776.558845] LNet: 462488:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6776.560767] LNetError: 462488:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6776.572371] LNet: Removed LNI 192.168.202.37@tcp [ 6776.810095] Key type .llcrypt unregistered [ 6776.811035] Key type ._llcrypt unregistered [ 6777.141552] Key type ._llcrypt registered [ 6777.147773] Key type .llcrypt registered [ 6777.342296] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6777.346875] alg: No test for adler32 (adler32-zlib) [ 6778.215278] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6778.300200] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6779.880111] Key type lgssc registered [ 6780.222199] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6783.322573] Lustre: DEBUG MARKER: Iteration 34 [ 6783.413286] LustreError: 463316:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6783.413316] LustreError: 463318:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6783.416775] LustreError: 463316:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6784.459617] Lustre: Mounted lustre-client [ 6784.882572] LustreError: 463421:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b86694000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6784.884461] LustreError: 463420:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6784.887059] LustreError: 463421:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6784.906155] Lustre: Unmounted lustre-client [ 6785.783041] Key type lgssc unregistered [ 6785.898548] LNet: 463664:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6785.900520] LNetError: 463664:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6785.910716] LNet: Removed LNI 192.168.202.37@tcp [ 6786.134093] Key type .llcrypt unregistered [ 6786.135047] Key type ._llcrypt unregistered [ 6786.378679] Key type ._llcrypt registered [ 6786.379707] Key type .llcrypt registered [ 6786.563842] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6786.569676] alg: No test for adler32 (adler32-zlib) [ 6787.410069] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6787.494584] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6789.072112] Key type lgssc registered [ 6789.426420] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6792.511784] Lustre: DEBUG MARKER: Iteration 35 [ 6792.612311] LustreError: 464492:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6792.612352] LustreError: 464493:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6792.615936] LustreError: 464492:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6793.670311] Lustre: Mounted lustre-client [ 6793.671303] Lustre: Skipped 1 previous similar message [ 6794.092039] LustreError: 464594:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b98cc6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6794.094082] LustreError: 464593:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6794.094850] LustreError: 464594:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6794.120482] Lustre: Unmounted lustre-client [ 6795.337301] Key type lgssc unregistered [ 6795.464565] LNet: 464836:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6795.466836] LNetError: 464836:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6795.474855] LNet: Removed LNI 192.168.202.37@tcp [ 6795.686102] Key type .llcrypt unregistered [ 6795.686979] Key type ._llcrypt unregistered [ 6795.926773] Key type ._llcrypt registered [ 6795.929320] Key type .llcrypt registered [ 6796.106398] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6796.110646] alg: No test for adler32 (adler32-zlib) [ 6796.950201] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6797.022624] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6798.600219] Key type lgssc registered [ 6799.245064] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6803.136898] Lustre: DEBUG MARKER: Iteration 36 [ 6803.267650] LustreError: 465664:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6803.268682] LustreError: 465665:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6803.271351] LustreError: 465664:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6804.322964] Lustre: Mounted lustre-client [ 6804.323863] Lustre: Skipped 1 previous similar message [ 6804.744985] LustreError: 465765:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b86691000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6804.747243] LustreError: 465764:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6804.748209] LustreError: 465765:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6804.783348] Lustre: Unmounted lustre-client [ 6805.773826] Key type lgssc unregistered [ 6805.880300] LNet: 466006:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6805.882202] LNetError: 466006:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6805.888735] LNet: Removed LNI 192.168.202.37@tcp [ 6806.121101] Key type .llcrypt unregistered [ 6806.122495] Key type ._llcrypt unregistered [ 6806.423146] Key type ._llcrypt registered [ 6806.425161] Key type .llcrypt registered [ 6806.575304] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6806.579811] alg: No test for adler32 (adler32-zlib) [ 6807.427605] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6807.500897] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6809.080147] Key type lgssc registered [ 6809.522398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6812.863872] Lustre: DEBUG MARKER: Iteration 37 [ 6812.973931] LustreError: 466836:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6812.973966] LustreError: 466835:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6812.977656] LustreError: 466836:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6814.032260] Lustre: Mounted lustre-client [ 6814.033149] Lustre: Skipped 1 previous similar message [ 6814.447856] LustreError: 466940:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b8669f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6814.452364] LustreError: 466941:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6814.455017] LustreError: 466941:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6814.506078] Lustre: Unmounted lustre-client [ 6815.744761] Key type lgssc unregistered [ 6815.850422] LNet: 467183:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6815.852346] LNetError: 467183:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6815.861689] LNet: Removed LNI 192.168.202.37@tcp [ 6816.073102] Key type .llcrypt unregistered [ 6816.074442] Key type ._llcrypt unregistered [ 6816.296502] Key type ._llcrypt registered [ 6816.297370] Key type .llcrypt registered [ 6816.472310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6816.477607] alg: No test for adler32 (adler32-zlib) [ 6817.317734] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6817.390749] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6818.968101] Key type lgssc registered [ 6819.289927] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6822.534399] Lustre: DEBUG MARKER: Iteration 38 [ 6822.630473] LustreError: 468011:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6822.630506] LustreError: 468012:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6822.634033] LustreError: 468011:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6823.683747] Lustre: Mounted lustre-client [ 6823.685147] Lustre: Skipped 1 previous similar message [ 6824.091634] LustreError: 468113:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1bb05bc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6824.097200] LustreError: 468114:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6824.098912] LustreError: 468114:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6824.138581] Lustre: Unmounted lustre-client [ 6825.042084] Key type lgssc unregistered [ 6825.155517] LNet: 468355:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6825.157355] LNetError: 468355:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6825.164722] LNet: Removed LNI 192.168.202.37@tcp [ 6825.379098] Key type .llcrypt unregistered [ 6825.379945] Key type ._llcrypt unregistered [ 6825.609630] Key type ._llcrypt registered [ 6825.611429] Key type .llcrypt registered [ 6825.778837] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6825.783265] alg: No test for adler32 (adler32-zlib) [ 6826.625357] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6826.697689] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6828.272104] Key type lgssc registered [ 6828.606528] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6831.888531] Lustre: DEBUG MARKER: Iteration 39 [ 6831.987419] LustreError: 469185:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6831.987446] LustreError: 469184:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6831.990981] LustreError: 469185:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6833.042349] Lustre: Mounted lustre-client [ 6833.043248] Lustre: Skipped 1 previous similar message [ 6833.467336] LustreError: 469287:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b86695000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6833.469224] LustreError: 469286:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6833.470580] LustreError: 469287:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6833.538107] Lustre: Unmounted lustre-client [ 6834.382036] Key type lgssc unregistered [ 6834.495352] LNet: 469530:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6834.497262] LNetError: 469530:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6834.503769] LNet: Removed LNI 192.168.202.37@tcp [ 6834.724112] Key type .llcrypt unregistered [ 6834.724977] Key type ._llcrypt unregistered [ 6834.975169] Key type ._llcrypt registered [ 6834.975963] Key type .llcrypt registered [ 6835.142428] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6835.146812] alg: No test for adler32 (adler32-zlib) [ 6836.012283] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6836.102376] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6837.680134] Key type lgssc registered [ 6838.003294] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6841.327888] Lustre: DEBUG MARKER: Iteration 40 [ 6841.425149] LustreError: 470358:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6841.425186] LustreError: 470359:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6841.429095] LustreError: 470358:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6842.473409] Lustre: Mounted lustre-client [ 6842.892148] LustreError: 470457:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ba0284000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6842.895295] LustreError: 470457:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6842.898942] LustreError: 470458:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6842.900948] LustreError: 470458:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6842.935482] Lustre: Unmounted lustre-client [ 6843.812828] Key type lgssc unregistered [ 6843.926830] LNet: 470699:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6843.929181] LNetError: 470699:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6843.936313] LNet: Removed LNI 192.168.202.37@tcp [ 6844.169111] Key type .llcrypt unregistered [ 6844.169932] Key type ._llcrypt unregistered [ 6844.429762] Key type ._llcrypt registered [ 6844.430712] Key type .llcrypt registered [ 6844.679382] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6844.684543] alg: No test for adler32 (adler32-zlib) [ 6845.528408] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6845.606654] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6847.184102] Key type lgssc registered [ 6847.507581] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6850.763142] Lustre: DEBUG MARKER: Iteration 41 [ 6850.865449] LustreError: 471528:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6850.865524] LustreError: 471529:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6850.869177] LustreError: 471528:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6851.920705] Lustre: Mounted lustre-client [ 6851.921648] Lustre: Skipped 1 previous similar message [ 6852.338391] LustreError: 471634:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ae873f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6852.340465] LustreError: 471633:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6852.341698] LustreError: 471634:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6852.386410] Lustre: Unmounted lustre-client [ 6853.343413] Key type lgssc unregistered [ 6853.459354] LNet: 471877:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6853.461204] LNetError: 471877:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6853.469727] LNet: Removed LNI 192.168.202.37@tcp [ 6853.686092] Key type .llcrypt unregistered [ 6853.687042] Key type ._llcrypt unregistered [ 6853.911614] Key type ._llcrypt registered [ 6853.912584] Key type .llcrypt registered [ 6854.090129] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6854.094229] alg: No test for adler32 (adler32-zlib) [ 6854.941395] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6855.015605] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6856.592101] Key type lgssc registered [ 6856.921220] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6860.315460] Lustre: DEBUG MARKER: Iteration 42 [ 6860.409827] LustreError: 472706:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6860.410074] LustreError: 472707:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6860.413721] LustreError: 472706:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6861.456269] Lustre: Mounted lustre-client [ 6861.457259] Lustre: Skipped 1 previous similar message [ 6861.889709] LustreError: 472808:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1baf09d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6861.892694] LustreError: 472808:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6861.893980] LustreError: 472807:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6861.932677] Lustre: Unmounted lustre-client [ 6862.882935] Key type lgssc unregistered [ 6863.006503] LNet: 473050:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6863.011359] LNetError: 473050:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6863.021608] LNet: Removed LNI 192.168.202.37@tcp [ 6863.321094] Key type .llcrypt unregistered [ 6863.321928] Key type ._llcrypt unregistered [ 6863.559888] Key type ._llcrypt registered [ 6863.560743] Key type .llcrypt registered [ 6863.728785] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6863.733180] alg: No test for adler32 (adler32-zlib) [ 6864.593161] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6864.668242] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6866.248169] Key type lgssc registered [ 6866.857766] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6870.531866] Lustre: DEBUG MARKER: Iteration 43 [ 6870.618243] LustreError: 473879:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6870.619309] LustreError: 473880:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6870.622129] LustreError: 473879:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6871.676107] Lustre: Mounted lustre-client [ 6872.096758] LustreError: 473982:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b98f97800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6872.101019] LustreError: 473982:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6872.102841] LustreError: 473982:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6872.123074] Lustre: Unmounted lustre-client [ 6872.970550] Key type lgssc unregistered [ 6873.084550] LNet: 474224:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6873.087435] LNetError: 474224:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6873.096720] LNet: Removed LNI 192.168.202.37@tcp [ 6873.302095] Key type .llcrypt unregistered [ 6873.303407] Key type ._llcrypt unregistered [ 6873.568884] Key type ._llcrypt registered [ 6873.570311] Key type .llcrypt registered [ 6873.722561] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6873.726830] alg: No test for adler32 (adler32-zlib) [ 6874.570140] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6874.641726] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6876.216100] Key type lgssc registered [ 6876.530293] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6879.512625] Lustre: DEBUG MARKER: Iteration 44 [ 6879.604833] LustreError: 475052:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6879.604872] LustreError: 475053:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6879.610073] LustreError: 475052:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6880.662249] Lustre: Mounted lustre-client [ 6881.073695] LustreError: 475156:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b8920f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6881.079098] LustreError: 475156:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6881.119098] Lustre: Unmounted lustre-client [ 6881.972216] Key type lgssc unregistered [ 6882.085511] LNet: 475399:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6882.087434] LNetError: 475399:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6882.096645] LNet: Removed LNI 192.168.202.37@tcp [ 6882.319087] Key type .llcrypt unregistered [ 6882.319963] Key type ._llcrypt unregistered [ 6882.601204] Key type ._llcrypt registered [ 6882.602503] Key type .llcrypt registered [ 6882.743652] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6882.748111] alg: No test for adler32 (adler32-zlib) [ 6883.590240] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6883.662413] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6885.240116] Key type lgssc registered [ 6885.554024] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6888.539240] Lustre: DEBUG MARKER: Iteration 45 [ 6888.634259] LustreError: 476227:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6888.636081] LustreError: 476228:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6888.638221] LustreError: 476227:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 6889.683124] Lustre: Mounted lustre-client [ 6889.683955] Lustre: Skipped 1 previous similar message [ 6890.106735] LustreError: 476328:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ae8739800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6890.108743] LustreError: 476327:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6890.109951] LustreError: 476328:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6890.155048] Lustre: Unmounted lustre-client [ 6891.000563] Key type lgssc unregistered [ 6891.113510] LNet: 476569:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6891.116652] LNetError: 476569:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6891.126725] LNet: Removed LNI 192.168.202.37@tcp [ 6891.334073] Key type .llcrypt unregistered [ 6891.335101] Key type ._llcrypt unregistered [ 6891.525260] Key type ._llcrypt registered [ 6891.526177] Key type .llcrypt registered [ 6891.701389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6891.715464] alg: No test for adler32 (adler32-zlib) [ 6892.553518] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6892.624509] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6894.200123] Key type lgssc registered [ 6894.524475] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6897.544238] Lustre: DEBUG MARKER: Iteration 46 [ 6897.629615] LustreError: 477408:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6897.629630] LustreError: 477409:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6897.633084] LustreError: 477408:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6898.678948] Lustre: Mounted lustre-client [ 6899.098703] LustreError: 477509:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b84d11000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6899.100594] LustreError: 477510:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6899.101494] LustreError: 477509:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6899.199564] Lustre: Unmounted lustre-client [ 6900.045514] Key type lgssc unregistered [ 6900.165517] LNet: 477753:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6900.167391] LNetError: 477753:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6900.175720] LNet: Removed LNI 192.168.202.37@tcp [ 6900.376090] Key type .llcrypt unregistered [ 6900.376920] Key type ._llcrypt unregistered [ 6900.584735] Key type ._llcrypt registered [ 6900.585723] Key type .llcrypt registered [ 6900.761271] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6900.766606] alg: No test for adler32 (adler32-zlib) [ 6901.606232] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6901.676890] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6903.248092] Key type lgssc registered [ 6903.558973] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6906.708404] Lustre: DEBUG MARKER: Iteration 47 [ 6906.802457] LustreError: 478582:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6906.802508] LustreError: 478583:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6906.807441] LustreError: 478582:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6907.855375] Lustre: Mounted lustre-client [ 6907.856558] Lustre: Skipped 1 previous similar message [ 6908.268787] LustreError: 478686:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1bb20d8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6908.272839] LustreError: 478686:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6908.315460] Lustre: Unmounted lustre-client [ 6909.146196] Key type lgssc unregistered [ 6909.252403] LNet: 478929:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6909.254248] LNetError: 478929:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6909.263441] LNet: Removed LNI 192.168.202.37@tcp [ 6909.475094] Key type .llcrypt unregistered [ 6909.475918] Key type ._llcrypt unregistered [ 6909.698969] Key type ._llcrypt registered [ 6909.700196] Key type .llcrypt registered [ 6909.868908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6909.875289] alg: No test for adler32 (adler32-zlib) [ 6910.722766] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6910.808081] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6912.384097] Key type lgssc registered [ 6912.717674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6915.907861] Lustre: DEBUG MARKER: Iteration 48 [ 6916.008937] LustreError: 479757:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6916.008968] LustreError: 479758:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6916.012436] LustreError: 479757:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6917.064946] Lustre: Mounted lustre-client [ 6917.484550] LustreError: 479861:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b84d13800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6917.489507] LustreError: 479861:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6917.491832] LustreError: 479861:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6917.534354] Lustre: Unmounted lustre-client [ 6918.380438] Key type lgssc unregistered [ 6918.494481] LNet: 480103:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6918.496267] LNetError: 480103:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6918.508750] LNet: Removed LNI 192.168.202.37@tcp [ 6918.721100] Key type .llcrypt unregistered [ 6918.722071] Key type ._llcrypt unregistered [ 6918.953127] Key type ._llcrypt registered [ 6918.954377] Key type .llcrypt registered [ 6919.137927] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6919.142939] alg: No test for adler32 (adler32-zlib) [ 6919.994068] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6920.080140] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6921.664255] Key type lgssc registered [ 6922.024958] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6925.134865] Lustre: DEBUG MARKER: Iteration 49 [ 6925.231094] LustreError: 480932:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6925.231336] LustreError: 480933:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6925.235718] LustreError: 480932:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6926.289357] Lustre: Mounted lustre-client [ 6926.290357] Lustre: Skipped 1 previous similar message [ 6926.799612] LustreError: 481036:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1b98fe0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6926.804347] LustreError: 481036:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6926.806049] LustreError: 481036:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6926.862636] Lustre: Unmounted lustre-client [ 6926.863643] Lustre: Skipped 1 previous similar message [ 6927.776439] Key type lgssc unregistered [ 6927.884422] LNet: 481278:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6927.886259] LNetError: 481278:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6927.892711] LNet: Removed LNI 192.168.202.37@tcp [ 6928.105128] Key type .llcrypt unregistered [ 6928.106435] Key type ._llcrypt unregistered [ 6928.353733] Key type ._llcrypt registered [ 6928.354685] Key type .llcrypt registered [ 6928.527419] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6928.531836] alg: No test for adler32 (adler32-zlib) [ 6929.386687] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6929.468390] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6931.048107] Key type lgssc registered [ 6931.415674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6934.743388] Lustre: DEBUG MARKER: Iteration 50 [ 6934.840125] LustreError: 482106:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6934.840167] LustreError: 482107:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6934.843732] LustreError: 482106:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6935.894832] Lustre: Mounted lustre-client [ 6935.895662] Lustre: Skipped 1 previous similar message [ 6936.311113] LustreError: 482211:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ba0287000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6936.313482] LustreError: 482211:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6936.317405] LustreError: 482211:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6936.318987] LustreError: 482211:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6936.346082] Lustre: Unmounted lustre-client [ 6937.195333] Key type lgssc unregistered [ 6937.306522] LNet: 482454:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6937.308368] LNetError: 482454:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6937.315725] LNet: Removed LNI 192.168.202.37@tcp [ 6937.530101] Key type .llcrypt unregistered [ 6937.531047] Key type ._llcrypt unregistered [ 6937.746600] Key type ._llcrypt registered [ 6937.747544] Key type .llcrypt registered [ 6937.951327] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6937.956474] alg: No test for adler32 (adler32-zlib) [ 6938.798843] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6938.871483] LNet: Added LNI 192.168.202.37@tcp [8/256/0/180] [ 6940.448107] Key type lgssc registered [ 6940.776317] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6944.611720] Lustre: Mounted lustre-client [ 6946.580877] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 08:29:28 (1756816168) [ 6963.168090] Lustre: 483793:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756816169/real 1756816169] req@ffff8b1bc1644380 x1842155262846848/t0(0) o36->lustre-MDT0000-mdc-ffff8b1ba0fd7800@192.168.202.137@tcp:12/10 lens 496/440 e 0 to 1 dl 1756816185 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6963.173715] Lustre: lustre-MDT0000-mdc-ffff8b1ba0fd7800: Connection to lustre-MDT0000 (at 192.168.202.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6963.181262] Lustre: lustre-MDT0000-mdc-ffff8b1ba0fd7800: Connection restored to 192.168.202.137@tcp (at 192.168.202.137@tcp) [ 6979.552094] Lustre: 483793:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756816185/real 1756816185] req@ffff8b1bc1644380 x1842155262846848/t0(0) o36->lustre-MDT0000-mdc-ffff8b1ba0fd7800@192.168.202.137@tcp:12/10 lens 496/440 e 0 to 1 dl 1756816201 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6979.563571] Lustre: lustre-MDT0000-mdc-ffff8b1ba0fd7800: Connection to lustre-MDT0000 (at 192.168.202.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6979.574552] Lustre: lustre-MDT0000-mdc-ffff8b1ba0fd7800: Connection restored to 192.168.202.137@tcp (at 192.168.202.137@tcp) [ 6994.912086] Lustre: 483793:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756816201/real 1756816201] req@ffff8b1bc1644380 x1842155262846848/t0(0) o36->lustre-MDT0000-mdc-ffff8b1ba0fd7800@192.168.202.137@tcp:12/10 lens 496/440 e 0 to 1 dl 1756816217 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6994.922660] Lustre: lustre-MDT0000-mdc-ffff8b1ba0fd7800: Connection to lustre-MDT0000 (at 192.168.202.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6994.931401] Lustre: lustre-MDT0000-mdc-ffff8b1ba0fd7800: Connection restored to 192.168.202.137@tcp (at 192.168.202.137@tcp) [ 7011.296088] Lustre: 483793:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756816217/real 1756816217] req@ffff8b1bc1644380 x1842155262846848/t0(0) o36->lustre-MDT0000-mdc-ffff8b1ba0fd7800@192.168.202.137@tcp:12/10 lens 496/440 e 0 to 1 dl 1756816233 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7011.301879] Lustre: lustre-MDT0000-mdc-ffff8b1ba0fd7800: Connection to lustre-MDT0000 (at 192.168.202.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7011.310403] Lustre: lustre-MDT0000-mdc-ffff8b1ba0fd7800: Connection restored to 192.168.202.137@tcp (at 192.168.202.137@tcp) [ 7011.754498] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 08:30:33 (1756816233) [ 7017.139508] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 08:30:38 (1756816238) [ 7020.393708] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 08:30:42 (1756816242) [ 7022.846812] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 08:30:44 (1756816244) [ 7030.046947] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 08:30:51 (1756816251) [ 7043.063018] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 08:31:04 (1756816264) [ 7045.128797] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 08:31:06 (1756816266) [ 7063.392131] Lustre: 482644:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756816269/real 1756816269] req@ffff8b1bb8a32d80 x1842155263876608/t0(0) o4->lustre-OST0000-osc-ffff8b1ba0fd7800@192.168.202.137@tcp:6/4 lens 4584/448 e 0 to 1 dl 1756816285 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7063.398403] Lustre: lustre-OST0000-osc-ffff8b1ba0fd7800: Connection to lustre-OST0000 (at 192.168.202.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7130.052540] Lustre: DEBUG MARKER: oleg237-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8b1ba0fd7800.ost_server_uuid 50 [ 7130.489869] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8b1ba0fd7800.ost_server_uuid in FULL state after 0 sec [ 7130.963507] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 7131.451450] Lustre: DEBUG MARKER: == sanityn test complete, duration 6946 sec ============== 08:32:33 (1756816353) [ 7131.911938] Lustre: DEBUG MARKER: === sanityn: start cleanup 08:32:33 (1756816353) === [ 7191.578244] LustreError: 490059:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1baf09c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7191.581764] LustreError: 490059:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7191.596068] Lustre: Unmounted lustre-client [ 7192.588686] Lustre: DEBUG MARKER: === sanityn: finish cleanup 08:33:34 (1756816414) === [ 7192.850236] LustreError: 490363:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8b1ba0fd7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7192.852622] LustreError: 490363:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 7192.857236] LustreError: 490363:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7192.858672] LustreError: 490363:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7192.889059] Lustre: Unmounted lustre-client [ 7229.824554] Key type lgssc unregistered [ 7229.945450] LNet: 491047:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7229.948435] LNetError: 491047:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7229.956852] LNet: Removed LNI 192.168.202.37@tcp [ 7230.197093] Key type .llcrypt unregistered [ 7230.198538] Key type ._llcrypt unregistered