[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 406201987 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001015] APIC: Switch to symmetric I/O mode setup [ 0.003119] x2apic enabled [ 0.004005] Switched APIC routing to physical x2apic. [ 0.005008] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009027] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010014] pid_max: default: 32768 minimum: 301 [ 0.011174] LSM: Security Framework initializing [ 0.012047] Yama: becoming mindful. [ 0.013030] SELinux: Initializing. [ 0.014105] *** VALIDATE selinux *** [ 0.022354] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026787] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027117] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028082] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029112] *** VALIDATE tmpfs *** [ 0.030486] *** VALIDATE proc *** [ 0.032183] *** VALIDATE cgroup *** [ 0.033006] *** VALIDATE cgroup2 *** [ 0.034246] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035136] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037026] Spectre V2 : User space: Vulnerable [ 0.038005] Speculative Store Bypass: Vulnerable [ 0.041480] debug: unmapping init [mem 0xffffffff91659000-0xffffffff91660fff] [ 0.043215] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044590] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045013] ... version: 2 [ 0.046007] ... bit width: 48 [ 0.047006] ... generic registers: 4 [ 0.048007] ... value mask: 0000ffffffffffff [ 0.049007] ... max period: 00007fffffffffff [ 0.050007] ... fixed-purpose events: 3 [ 0.051007] ... event mask: 000000070000000f [ 0.053250] rcu: Hierarchical SRCU implementation. [ 0.055656] smp: Bringing up secondary CPUs ... [ 0.056515] x86: Booting SMP configuration: [ 0.057013] .... node #0, CPUs: #1 #2 #3 [ 0.060081] smp: Brought up 1 node, 4 CPUs [ 0.062012] smpboot: Max logical packages: 1 [ 0.063014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.275590] node 0 deferred pages initialised in 211ms [ 0.278011] devtmpfs: initialized [ 0.278975] x86/mm: Memory block size: 128MB [ 0.280284] gcov: version magic: 0x41383552 [ 0.282103] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.283048] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.284230] pinctrl core: initialized pinctrl subsystem [ 0.285131] [ 0.285506] ************************************************************* [ 0.286006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.287006] ** ** [ 0.288006] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.289006] ** ** [ 0.290005] ** This means that this kernel is built to expose internal ** [ 0.291006] ** IOMMU data structures, which may compromise security on ** [ 0.292006] ** your system. ** [ 0.293006] ** ** [ 0.294005] ** If you see this message and you are not debugging the ** [ 0.295006] ** kernel, report this immediately to your vendor! ** [ 0.296006] ** ** [ 0.297005] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.298008] ************************************************************* [ 0.299539] NET: Registered protocol family 16 [ 0.300363] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.301028] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.302027] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.303315] cpuidle: using governor menu [ 0.304323] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.306481] PCI: Using configuration type 1 for base access [ 0.308088] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.316115] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.317010] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.318108] cryptd: max_cpu_qlen set to 1000 [ 0.320207] ACPI: Added _OSI(Module Device) [ 0.321009] ACPI: Added _OSI(Processor Device) [ 0.322007] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.323007] ACPI: Added _OSI(Processor Aggregator Device) [ 0.326072] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.332636] ACPI: Interpreter enabled [ 0.334053] ACPI: PM: (supports S0 S3 S4 S5) [ 0.335009] ACPI: Using IOAPIC for interrupt routing [ 0.337077] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.341399] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.353480] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.355024] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.358010] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.361062] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.366136] acpiphp: Slot [2] registered [ 0.367084] acpiphp: Slot [5] registered [ 0.368093] acpiphp: Slot [6] registered [ 0.369049] acpiphp: Slot [3] registered [ 0.370013] acpiphp: Slot [4] registered [ 0.370940] acpiphp: Slot [7] registered [ 0.372046] acpiphp: Slot [8] registered [ 0.372964] acpiphp: Slot [9] registered [ 0.374046] acpiphp: Slot [10] registered [ 0.375019] acpiphp: Slot [11] registered [ 0.375994] acpiphp: Slot [12] registered [ 0.376046] acpiphp: Slot [13] registered [ 0.377026] acpiphp: Slot [14] registered [ 0.377958] acpiphp: Slot [15] registered [ 0.379083] acpiphp: Slot [16] registered [ 0.380066] acpiphp: Slot [17] registered [ 0.382152] acpiphp: Slot [18] registered [ 0.383054] acpiphp: Slot [19] registered [ 0.383978] acpiphp: Slot [20] registered [ 0.385048] acpiphp: Slot [21] registered [ 0.386046] acpiphp: Slot [22] registered [ 0.386981] acpiphp: Slot [23] registered [ 0.388057] acpiphp: Slot [24] registered [ 0.389032] acpiphp: Slot [25] registered [ 0.390027] acpiphp: Slot [26] registered [ 0.391010] acpiphp: Slot [27] registered [ 0.391965] acpiphp: Slot [28] registered [ 0.393046] acpiphp: Slot [29] registered [ 0.394010] acpiphp: Slot [30] registered [ 0.394941] acpiphp: Slot [31] registered [ 0.395063] PCI host bridge to bus 0000:00 [ 0.397013] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.398008] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.400008] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.401008] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.403008] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.405010] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.406138] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.408942] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.410848] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.416514] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.419410] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.421008] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.423009] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.424007] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.425463] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.427517] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.430019] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.431556] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.434007] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.439901] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.442755] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.446302] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.450956] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.454007] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.465017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.473314] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.478008] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.482007] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.494017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.500650] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.502233] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.504191] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.506193] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.507100] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.511084] iommu: Default domain type: Passthrough [ 0.512558] SCSI subsystem initialized [ 0.514126] ACPI: bus type USB registered [ 0.516084] usbcore: registered new interface driver usbfs [ 0.518059] usbcore: registered new interface driver hub [ 0.520075] usbcore: registered new device driver usb [ 0.522187] pps_core: LinuxPPS API ver. 1 registered [ 0.524009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.527042] PTP clock support registered [ 0.529217] EDAC MC: Ver: 3.0.0 [ 0.531110] PCI: Using ACPI for IRQ routing [ 0.532575] NetLabel: Initializing [ 0.533006] NetLabel: domain hash size = 128 [ 0.533892] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.535048] NetLabel: unlabeled traffic allowed by default [ 0.536135] vgaarb: loaded [ 0.537226] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.539008] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.543324] clocksource: Switched to clocksource kvm-clock [ 0.632784] VFS: Disk quotas dquot_6.6.0 [ 0.633807] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.635229] *** VALIDATE ramfs *** [ 0.635955] *** VALIDATE hugetlbfs *** [ 0.636897] pnp: PnP ACPI init [ 0.638658] pnp: PnP ACPI: found 6 devices [ 0.652082] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.654021] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.655309] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.656549] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.657893] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.659255] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.660963] NET: Registered protocol family 2 [ 0.662552] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.665710] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.667843] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.671735] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.674123] TCP: Hash tables configured (established 65536 bind 65536) [ 0.676270] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.678287] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.680243] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.682370] NET: Registered protocol family 1 [ 0.684404] RPC: Registered named UNIX socket transport module. [ 0.686738] RPC: Registered udp transport module. [ 0.688391] RPC: Registered tcp transport module. [ 0.690094] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.692476] NET: Registered protocol family 44 [ 0.693701] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.695133] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.696720] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.698554] PCI: CLS 0 bytes, default 64 [ 0.699861] Unpacking initramfs... [ 1.938834] debug: unmapping init [mem 0xffff8d63bcc64000-0xffff8d63bffcffff] [ 1.943479] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.946024] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 1.948737] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.506178] Initialise system trusted keyrings [ 2.507714] Key type blacklist registered [ 2.509473] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.518739] zbud: loaded [ 2.521752] *** VALIDATE nfs *** [ 2.522813] *** VALIDATE nfs4 *** [ 2.524180] pstore: using deflate compression [ 2.526910] Platform Keyring initialized [ 2.616777] NET: Registered protocol family 38 [ 2.617867] Key type asymmetric registered [ 2.619259] Asymmetric key parser 'x509' registered [ 2.620826] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.624000] io scheduler mq-deadline registered [ 2.625754] io scheduler kyber registered [ 2.627246] io scheduler bfq registered [ 2.629368] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.632379] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.635398] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.637953] ACPI: Power Button [PWRF] [ 2.644574] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.651163] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.662256] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.693862] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.720837] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.724678] Non-volatile memory driver v1.3 [ 2.725726] Linux agpgart interface v0.103 [ 2.754204] virtio_blk virtio1: [vda] 67992 512-byte logical blocks (34.8 MB/33.2 MiB) [ 2.757407] vda: detected capacity change from 0 to 34811904 [ 2.774447] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.777620] vdb: detected capacity change from 0 to 1073741824 [ 2.783279] libphy: Fixed MDIO Bus: probed [ 2.787879] usbcore: registered new interface driver usbserial_generic [ 2.789835] usbserial: USB Serial support registered for generic [ 2.791336] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.795480] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.797327] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.799340] mousedev: PS/2 mouse device common for all mice [ 2.801964] rtc_cmos 00:05: RTC can wake from S4 [ 2.803705] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.805093] rtc_cmos 00:05: registered as rtc0 [ 2.807938] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.810208] intel_pstate: CPU model not supported [ 2.812670] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.814490] hid: raw HID events driver (C) Jiri Kosina [ 2.817827] usbcore: registered new interface driver usbhid [ 2.817854] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.819019] usbhid: USB HID core driver [ 2.819159] drop_monitor: Initializing network drop monitor service [ 2.823755] Initializing XFRM netlink socket [ 2.825692] NET: Registered protocol family 10 [ 2.828015] Segment Routing with IPv6 [ 2.829404] NET: Registered protocol family 17 [ 2.830735] mpls_gso: MPLS GSO support [ 2.834200] RAS: Correctable Errors collector initialized. [ 2.835641] AVX version of gcm_enc/dec engaged. [ 2.836801] AES CTR mode by8 optimization enabled [ 2.897636] sched_clock: Marking stable (2897610015, 0)->(3638554036, -740944021) [ 2.900618] registered taskstats version 1 [ 2.902139] Loading compiled-in X.509 certificates [ 2.903504] zswap: loaded using pool lzo/zbud [ 2.928791] Key type big_key registered [ 2.944265] Key type encrypted registered [ 2.945882] ima: No TPM chip found, activating TPM-bypass! [ 2.948039] ima: Allocated hash algorithm: sha1 [ 2.949687] ima: No architecture policies found [ 2.951238] evm: Initialising EVM extended attributes: [ 2.952944] evm: security.selinux [ 2.954139] evm: security.ima [ 2.954977] evm: security.capability [ 2.956073] evm: HMAC attrs: 0x1 [ 2.958169] rtc_cmos 00:05: setting system clock to 2026-02-28 18:51:30 UTC (1772304690) [ 2.963826] debug: unmapping init [mem 0xffffffff92603000-0xffffffff927fffff] [ 2.966308] debug: unmapping init [mem 0xffffffff91382000-0xffffffff91658fff] [ 2.974084] Write protecting the kernel read-only data: 28672k [ 2.977305] debug: unmapping init [mem 0xffffffff8fa03000-0xffffffff8fbfffff] [ 2.980070] debug: unmapping init [mem 0xffffffff90314000-0xffffffff903fffff] [ 3.003614] 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.008782] systemd[1]: Detected virtualization kvm. [ 3.010019] systemd[1]: Detected architecture x86-64. [ 3.011360] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.037181] systemd[1]: No hostname configured. [ 3.038583] systemd[1]: Set hostname to . [ 3.040218] random: systemd: uninitialized urandom read (16 bytes read) [ 3.042177] systemd[1]: Initializing machine ID from random generator. [ 3.093583] random: ln: uninitialized urandom read (6 bytes read) [ 3.184785] random: systemd: uninitialized urandom read (16 bytes read) [ 3.187437] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.191526] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.193945] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Reached target Local File Systems. [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... Starting Journal Service... Starting Create Volatile Files and Directories... Starting Setup Virtual Console... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. 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... [ 3.779431] device-mapper: uevent: version 1.0.3 [ 3.781508] 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. [ 4.548524] virtio_net virtio0 ens2: renamed from eth0 [ 4.644742] scsi host0: ata_piix [ 4.655061] scsi host1: ata_piix [ 4.657700] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.659569] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.460959] random: crng init done [ 9.461822] random: 7 urandom warning(s) missed due to ratelimiting [ 9.869990] dracut-initqueue[586]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 11.071281] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ 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 Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ 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 Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 12.322560] printk: systemd: 25 output lines suppressed due to ratelimiting [ 12.639425] SELinux: Disabled at runtime. [ 12.703410] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 12.713557] systemd[1]: Detected virtualization kvm. [ 12.715546] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 13.290600] systemd[1]: initrd-switch-root.service: Succeeded. [ 13.292833] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 13.304565] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 13.310685] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 13.314917] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 13.322715] systemd[1]: Starting Journal Service... Starting Journal Service... [ 13.330276] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. Mounting POSIX Message Queue File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice User and Session Slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Switch Root. [ OK ] Reached target Slices. Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Kernel Soc[ 13.520771] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS ket. Starting udev Coldplug all Devices... Mounting Kernel Debug File System... Mounting Huge Pages 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. Starting Apply Kernel Variables... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [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 ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 14.020928] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 14.346108] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 14.348355] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 14.489353] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 14.543465] EDAC sbridge: Ver: 1.1.2 [ 16.117879] Key type dns_resolver registered [ 16.520290] NFS: Registering the id_resolver key type [ 16.523200] Key type id_resolver registered [ 16.525373] Key type id_legacy registered [ 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 ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... [ OK ] Started Login Service. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started OpenSSH server daemon. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg229-client login: [ 41.897608] libcfs: loading out-of-tree module taints kernel. [ 41.989508] alg: No test for adler32 (adler32-zlib) [ 42.745338] Key type ._llcrypt registered [ 42.746191] Key type .llcrypt registered [ 42.856727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 43.040500] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 43.275360] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 43.276874] LNet: Accept secure, port 988 [ 44.880107] Key type lgssc registered [ 45.347402] Lustre: Echo OBD driver; http://www.lustre.org/ [ 89.816569] Lustre: Mounted lustre-client [ 92.229722] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 103.061178] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing check_logdir /tmp/testlogs/ [ 104.529926] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing yml_node [ 105.825050] Lustre: DEBUG MARKER: Client: 2.15.8.1 [ 106.602235] Lustre: DEBUG MARKER: MDS: 2.15.8.1 [ 107.416889] Lustre: DEBUG MARKER: OSS: 2.15.8.1 [ 107.893130] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Sat Feb 28 13:53:14 EST 2026 [ 110.380131] Lustre: DEBUG MARKER: excepting tests: 27 28 102 [ 110.561114] Lustre: lustre-OST0000-osc-ffff8d6418e92800: disconnect after 20s idle [ 110.849755] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 111.032678] Lustre: Mounted lustre-client [ 112.808679] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing check_config_client /mnt/lustre [ 117.931035] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 120.857643] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 13:53:27 (1772304807) [ 122.927656] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 13:53:30 (1772304810) [ 124.920619] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 13:53:32 (1772304812) [ 126.949670] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 13:53:34 (1772304814) [ 128.843592] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 13:53:35 (1772304815) [ 130.699287] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 13:53:37 (1772304817) [ 132.595770] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 13:53:39 (1772304819) [ 133.034678] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 133.559441] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 13:53:40 (1772304820) [ 135.666291] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 13:53:42 (1772304822) [ 137.806694] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 13:53:44 (1772304824) [ 140.864560] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 13:53:47 (1772304827) [ 142.858516] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 13:53:49 (1772304829) [ 144.719376] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 13:53:51 (1772304831) [ 146.543266] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 13:53:53 (1772304833) [ 148.580367] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 13:53:55 (1772304835) [ 150.737957] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 13:53:57 (1772304837) [ 152.726525] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 13:53:59 (1772304839) [ 155.016271] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 13:54:02 (1772304842) [ 157.100535] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 13:54:04 (1772304844) [ 159.236015] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 13:54:06 (1772304846) [ 159.364593] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502294 file: /mnt/lustre/lockdir/lockfile=144115205272502292 [ 300.754904] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 13:56:27 (1772304987) [ 303.101422] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 13:56:30 (1772304990) [ 305.283117] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 13:56:32 (1772304992) [ 307.577207] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 13:56:34 (1772304994) [ 309.741944] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 13:56:36 (1772304996) [ 311.959939] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 13:56:39 (1772304999) [ 312.511489] Lustre: DEBUG MARKER: chmod [ 314.589473] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 13:56:41 (1772305001) [ 317.386433] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7519232kB free gt MAXFREE 800000kB, increase 800000 (or reduce test fs size) to proceed [ 326.227352] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 13:56:53 (1772305013) [ 340.792172] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 13:57:07 (1772305027) [ 348.753698] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 13:57:15 (1772305035) [ 349.563484] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 350.073448] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 13:57:17 (1772305037) [ 361.712744] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 13:57:28 (1772305048) [ 363.988206] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 13:57:31 (1772305051) [ 364.336164] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.411585] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.431968] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.450163] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.473754] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.493193] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.513707] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.534115] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.553796] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.574331] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.594367] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.614709] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.634497] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.654460] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.674754] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.695498] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.716536] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.736346] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.756408] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.776821] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.798054] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.820035] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.841415] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.864842] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.885665] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.906349] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.929901] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.951888] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 364.976263] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.001861] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.028394] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.054125] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.077318] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.098693] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.117577] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.137880] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.157911] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.177877] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.197088] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.221146] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.244323] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.267820] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.294121] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.322250] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.352313] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.380369] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.410069] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.432188] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.454157] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.476429] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.504164] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.525176] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.545528] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.568102] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.593679] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.622716] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.648195] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.672536] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.694418] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.716737] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.739501] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.761443] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.787524] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.813918] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.840584] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.862850] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.885902] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.909907] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.935520] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.959297] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 365.980662] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.008979] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.036306] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.064768] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.089678] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.115948] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.141901] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.167755] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.200484] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.227134] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.252104] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.278859] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.301433] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.327098] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.353105] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.378200] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.403911] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.433582] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.456299] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.483623] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.508812] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.535681] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.563921] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.593122] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.622699] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.644682] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.667073] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.692160] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.718533] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.742926] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.765204] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.786022] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.806758] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.828318] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.850228] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.874775] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.900301] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.924732] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.947908] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.970656] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 366.996293] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.018715] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.043705] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.073650] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.100058] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.125765] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.151265] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.176728] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.204362] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.231233] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.257980] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.281923] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.307051] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.339857] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.365737] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.392755] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.417147] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.442906] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.465949] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.489716] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.512952] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.538993] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.564612] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.589680] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.613673] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.639906] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.669870] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.711290] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.740775] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.777216] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.807707] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.836744] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.862394] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.886609] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.908064] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.932067] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.960104] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 367.986615] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.008680] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.029778] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.050553] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.073432] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.096470] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.120824] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.145646] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.169106] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.196754] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.222476] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.247790] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.275860] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.301260] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.327749] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.353698] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.386971] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.410545] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.433969] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.455808] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.478508] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.500895] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.524592] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.546600] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.574724] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.605771] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.635924] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.662348] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.686763] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.711131] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.735654] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.760920] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.786797] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.812631] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.839348] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.863709] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.888789] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.917741] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.943697] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.966337] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 368.987149] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.010468] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.034335] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.064376] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.088151] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.111249] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.133312] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.156550] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.184469] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.214052] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.241432] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.267801] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.293623] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.318538] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.341669] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.363918] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.387426] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.417663] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.445667] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.467820] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.492404] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.517794] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.540515] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.563863] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.592660] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.623706] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.645571] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.667341] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.694043] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.715393] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.735748] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.760651] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.786783] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.811894] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.840281] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.862850] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.886618] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.911104] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.939078] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.961436] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 369.984909] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.007862] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.032710] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.059057] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.085090] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.112234] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.137909] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.168901] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.196693] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.219830] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.245820] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.273898] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.300780] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.330961] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.358552] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.387203] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.418161] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.442208] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.468117] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.494825] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.519800] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.551195] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.575795] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.603948] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.626417] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.650947] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.678375] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.701620] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.725923] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.749920] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.773222] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.797069] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.822444] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.844411] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.865735] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.897899] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.931921] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.972751] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 370.999125] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.022491] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.044899] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.070114] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.091691] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.113685] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.134681] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.155794] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.177402] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.197855] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.220598] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.245994] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.271925] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.294343] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.326694] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.353158] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.385834] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.414773] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.441378] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.473768] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.509507] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.542269] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.574859] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.601249] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.627835] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.652565] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.673547] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.703177] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.726474] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.749491] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.773124] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.803430] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.827228] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.849543] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.871525] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.896885] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.918763] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.941408] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.965220] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 371.994104] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.015603] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.043690] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.071047] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.093308] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.117550] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.147364] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.175623] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.199437] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.222670] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.249505] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.273968] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.305829] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.332008] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.355174] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.384580] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.418040] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.441848] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.463996] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.489870] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.515870] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.539918] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.571164] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.600881] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.628483] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.655036] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.682351] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.709100] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.732779] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.753555] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.775636] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.809146] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.839530] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.870784] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.892228] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.917872] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.938854] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.961439] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 372.983061] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.008901] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.034418] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.055827] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.079380] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.101225] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.126259] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.149653] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.170588] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.192348] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.220617] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.246581] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.267869] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.291303] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.314738] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.337971] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.359465] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.381329] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.402689] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.423663] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.447569] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.471163] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.496764] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.525541] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.546973] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.566491] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.587668] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.608490] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.630409] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.653253] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.676831] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.700849] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.723080] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.745221] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.769628] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.794364] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.818844] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.842256] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.865288] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.887874] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.914620] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.937617] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.958996] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 373.980799] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.002385] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.025437] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.047870] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.071896] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.094592] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.117025] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.139261] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.161700] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.183627] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.207059] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.230091] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.254665] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.278169] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.300088] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.320155] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.340700] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.360175] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.379375] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.401434] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.423102] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.445789] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.468414] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.489606] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.509965] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.532172] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.556449] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.580086] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.603495] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.627656] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.650439] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.677300] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.702836] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.726475] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.748902] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.772781] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.798785] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.825051] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.847383] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.870962] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.891151] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.911230] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.931425] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.952713] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.973285] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 374.993977] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.013216] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.033315] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.054692] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.079214] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.102037] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.127908] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.149468] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.173824] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.195793] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.218584] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.239765] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.259880] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.280378] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.298772] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.317711] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.337982] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.360442] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.383119] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.403927] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.426314] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.447784] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.477794] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.514487] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.542126] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.565119] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.589864] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.613225] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.643283] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.668687] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.689473] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.714795] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.746629] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.776718] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.803926] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.826828] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.850068] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.875962] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.901942] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.925820] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.951184] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 375.975187] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.001464] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.025307] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.047789] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.072194] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.095875] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.119307] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.142940] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.166183] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.188116] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.210396] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.233876] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.258909] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.284386] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.315405] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.341625] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.365594] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.388514] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.412136] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.435053] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.458362] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.487503] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.515453] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.538992] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.570081] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.603584] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.627246] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.651778] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.678401] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.704311] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.725390] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.747833] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.770624] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.796534] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.822323] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.848363] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.873326] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.899130] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.923803] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.944875] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 376.975781] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.003742] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.026979] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.052352] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.076979] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.099305] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.119603] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.147615] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.174464] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.198600] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.223436] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.249648] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.271493] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.295953] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.318860] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.342511] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.366993] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.390055] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.416877] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.441198] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.468151] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.493587] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.517721] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.541680] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.568392] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.591498] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.615408] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.636470] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.657537] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.680027] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.700813] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.723117] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.746821] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.770550] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.794402] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.819720] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.846734] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.873400] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.897534] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.923203] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.950155] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 377.975506] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.000616] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.023149] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.049558] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.075320] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.100162] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.127080] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.151969] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.177298] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.201382] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.225792] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.249154] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.273479] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.297437] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.323414] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.345810] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.369546] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.393808] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.415134] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.441431] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.462405] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.492729] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.514128] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.538337] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.573421] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.598533] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.620325] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.644321] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.667155] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.691165] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.718221] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.744478] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.767847] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.791340] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.817664] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.842245] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.865189] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.889359] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.914845] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.942926] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.971850] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 378.995061] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.016886] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.037925] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.074991] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.100386] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.121111] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.142501] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.163119] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.194670] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.224390] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.250186] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.275439] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.298236] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.321323] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.342934] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.370643] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.396470] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.420673] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.440955] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.461684] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.481616] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.506555] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.534919] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.556520] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.581870] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.605787] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.631511] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.655845] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.682084] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.704932] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.725559] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.746679] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.773793] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.802682] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.831476] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.857088] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.879439] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.903445] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.932767] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.960061] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 379.984712] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.009704] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.034770] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.058290] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.087418] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.118827] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.151837] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.178481] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.206860] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.233041] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.260424] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.286376] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.311888] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.338203] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.368055] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.392759] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.419966] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.447871] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.477020] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.503800] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.527349] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.556025] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.581923] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.609503] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.635396] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.666900] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.692347] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.719673] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.745922] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.770450] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.795740] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.822790] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.853155] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.878779] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.907606] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.935860] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.961692] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 380.988125] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.018680] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.040524] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.066368] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.092548] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.120881] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.142954] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.162583] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.182518] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.204350] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.226290] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.248502] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.270572] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.293596] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.318469] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.341906] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.366222] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.387959] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.407132] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.424842] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.442196] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.459024] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.475702] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.496287] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.516277] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.535590] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.553946] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.572143] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.590366] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.608403] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.626550] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.644910] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.665792] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.683703] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.704510] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.728037] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.749174] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.769153] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.792090] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.812919] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.833138] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.853456] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.873661] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.892975] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.912468] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.932127] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.954540] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.973348] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 381.993524] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.016573] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.042837] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.065490] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.090641] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.116913] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.142611] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.166625] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.189579] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.212746] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.233256] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.256474] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.278744] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.304331] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.325380] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.345816] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.367124] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.389276] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.416851] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.442927] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.466689] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.488745] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.511629] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.537101] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.568383] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.596631] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.623698] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.649927] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.675265] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.699133] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.723911] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.750753] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.781754] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.810327] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.839529] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.865616] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.890127] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.916082] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.940698] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.964451] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 382.990524] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.020406] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.048259] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.072319] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.093558] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.113356] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.133840] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.159280] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.181069] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.202428] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.222036] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.241853] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.265054] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.289140] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.317337] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.347237] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.378086] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.407614] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.434789] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.459235] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.482151] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.508739] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.535547] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.562350] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.590086] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.617316] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.640763] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.664045] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.689582] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.711495] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.736401] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.763906] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.788703] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.815392] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.839885] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.869424] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.893225] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.919826] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.948093] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.970986] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 383.994082] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 384.011385] rw_seq_cst_vs_d (27460): drop_caches: 3 [ 386.529300] Lustre: lustre-OST0000-osc-ffff8d6418e92800: disconnect after 21s idle [ 386.532593] Lustre: Skipped 1 previous similar message [ 387.352522] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 13:57:54 (1772305074) [ 387.495537] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.531341] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.594573] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.638756] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.677431] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.712386] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.766249] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.815217] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.857951] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.880921] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.899951] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.921824] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 387.991751] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.030210] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.070510] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.127607] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.153563] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.230959] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.287309] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.304476] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.323988] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.337831] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.354668] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.421189] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.484174] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.504879] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.527530] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.548744] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.570965] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.630664] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.649538] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.677851] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.761627] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.821630] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.857252] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.957185] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 388.997254] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.058543] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.080596] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.095956] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.113859] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.153553] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.237266] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.253250] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.268786] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.282872] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.536279] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.557528] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.597329] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.617970] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.759913] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.786686] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.819390] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.901800] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.960832] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 389.992212] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.014758] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.083783] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.179335] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.202079] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.242166] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.277149] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.490724] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.601435] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.642846] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.668329] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.686534] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.765787] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.790286] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.812542] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.834424] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 390.879231] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.267675] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.284063] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.301394] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.386766] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.502316] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.536395] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.570151] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.585877] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.747711] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.784143] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.801051] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.820764] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.916634] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.932983] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.949304] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.965468] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 391.981034] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.006077] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.026735] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.046407] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.069518] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.108144] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.182779] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.223852] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.242468] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.304624] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.326318] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.388742] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.602132] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.683984] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.720638] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.765294] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.791342] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.832735] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.892070] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 392.984423] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 393.000121] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 393.080934] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 393.109879] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 393.171439] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 393.210670] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 393.444995] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 393.462662] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 393.499435] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 393.556925] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 393.579722] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.039766] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.058621] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.118332] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.147851] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.196654] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.218033] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.241676] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.285080] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.326037] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.643501] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.670580] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.695967] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.711590] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.731680] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.947873] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.965407] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 394.982784] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.006474] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.029305] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.062291] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.077271] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.098866] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.134422] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.229651] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.252292] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.281424] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.301705] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.343462] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.363740] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.385685] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.465202] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.510129] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 395.779284] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.025437] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.163606] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.201430] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.222719] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.240761] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.283883] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.322180] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.337206] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.359466] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.394492] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.415573] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.440402] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.539914] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.562667] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.600689] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.626589] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.646946] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.669922] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.686775] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.710578] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.749861] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.772108] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.790722] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.871441] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 396.985142] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.048140] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.104178] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.140784] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.221126] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.255500] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.277615] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.352572] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.374611] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.413097] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.451766] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.466680] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.482110] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.509338] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.527329] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.547077] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.564462] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.582919] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.703622] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.726050] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.946919] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 397.967045] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.025471] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.044116] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.081154] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.114846] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.137204] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.159522] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.175662] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.233658] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.253701] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.290676] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.350308] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.401488] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.453884] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.490511] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.527378] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.546685] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.567518] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.637692] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.651220] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.669597] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.689555] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.708162] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.739276] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.770897] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.789514] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.808745] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.822570] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.836145] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.849869] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.868373] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.882435] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.913379] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.932118] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.946331] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 398.979847] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.030497] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.052156] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.085307] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.107325] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.162249] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.195843] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.252985] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.272138] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.291724] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.326778] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.364475] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.401498] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.434787] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.466967] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.499297] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.531322] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.546122] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.577183] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.610236] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.625072] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.659255] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.696536] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.712741] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.750631] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.802427] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.860879] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.876137] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.907892] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.943700] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 399.994858] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.066608] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.099022] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.119660] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.154669] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.195955] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.212650] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.228490] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.250326] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.275683] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.535607] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.562344] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.584940] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.604590] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.626532] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.660502] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.699394] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.758676] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.793523] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.815581] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.832095] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.853527] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.869589] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 400.985633] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.006550] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.022367] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.037708] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.151209] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.166654] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.181904] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.204123] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.265672] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.286571] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.302384] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.317458] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.342526] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.363175] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.402744] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.428544] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.450995] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.480934] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.504173] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.542594] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.560188] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.581196] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.599341] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.660639] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.716663] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.752791] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.829108] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.869891] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 401.992527] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.013051] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.033254] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.051047] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.107588] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.132074] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.193301] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.236164] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.257128] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.358734] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.377916] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.395799] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.411220] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.425738] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.441808] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.457731] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.472622] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.487537] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.503252] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.518896] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.540072] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.555867] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.573166] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.614853] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.693894] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.720197] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.824431] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 402.846056] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.372126] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.394994] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.416742] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.433713] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.599543] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.636932] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.657724] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.673390] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.709569] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.725134] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.751645] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.776983] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.797065] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.810258] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.822258] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.837080] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.852616] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.923796] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.956076] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.976279] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 403.997070] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.092075] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.129297] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.204717] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.239127] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.254291] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.269737] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.292603] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.310765] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.327490] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.388165] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.408087] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.427778] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.446719] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.468259] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.491782] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.539425] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.553061] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.567913] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.588519] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.606363] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.665443] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 404.703460] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.064916] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.082307] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.099861] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.137940] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.158649] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.194327] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.233801] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.250315] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.285489] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.366611] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.392200] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.412773] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.433131] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.455164] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.513230] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.755833] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.778263] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.801771] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.880467] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 405.898537] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 406.246550] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 406.302178] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 406.459249] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 406.476393] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 406.509233] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 406.545305] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 406.786229] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 407.008158] Lustre: lustre-OST0001-osc-ffff8d6418e92800: disconnect after 21s idle [ 407.012205] Lustre: Skipped 1 previous similar message [ 407.074813] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 407.116030] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 407.274380] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 407.298274] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 407.344556] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 407.361202] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 407.378196] rw_seq_cst_vs_d (28034): drop_caches: 3 [ 409.709490] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 13:58:16 (1772305096) [ 411.971612] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 13:58:19 (1772305099) [ 416.988496] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 13:58:24 (1772305104) [ 432.318160] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 13:58:39 (1772305119) [ 433.239481] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 433.820153] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 13:58:40 (1772305120) [ 435.928816] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 13:58:43 (1772305123) [ 437.999072] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 13:58:45 (1772305125) [ 447.968249] Lustre: lustre-OST0001-osc-ffff8d6402f5b800: disconnect after 20s idle [ 447.971827] Lustre: Skipped 1 previous similar message [ 458.208259] Lustre: lustre-OST0000-osc-ffff8d6418e92800: disconnect after 24s idle [ 501.624371] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 13:59:48 (1772305188) [ 503.780704] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 13:59:50 (1772305190) [ 505.802289] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 13:59:52 (1772305192) [ 507.988250] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 13:59:55 (1772305195) [ 508.520981] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 509.032616] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 13:59:56 (1772305196) [ 512.049375] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 13:59:59 (1772305199) [ 516.224562] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 516.756906] Lustre: DEBUG MARKER: SKIP: sanityn test_28 skipping ALWAYS excluded test 28 [ 517.256565] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 14:00:04 (1772305204) [ 521.498899] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 14:00:08 (1772305208) [ 521.558134] Lustre: *** cfs_fail_loc=314, val=0*** [ 522.592229] Lustre: *** cfs_fail_loc=314, val=0*** [ 522.593898] Lustre: Skipped 2 previous similar messages [ 524.541115] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 14:00:11 (1772305211) [ 534.441773] Lustre: *** cfs_fail_loc=314, val=0*** [ 534.461301] LustreError: 11-0: lustre-OST0000-osc-ffff8d6402f5b800: operation ldlm_enqueue to node 192.168.202.129@tcp failed: rc = -107 [ 534.463989] Lustre: lustre-OST0000-osc-ffff8d6402f5b800: Connection to lustre-OST0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 534.469161] LustreError: lustre-OST0000-osc-ffff8d6402f5b800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 534.472831] LustreError: 37269:0:(ldlm_resource.c:1127:ldlm_resource_complain()) lustre-OST0000-osc-ffff8d6402f5b800: namespace resource [0x24:0x0:0x0].0x0 (000000000938c063) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 534.480318] Lustre: lustre-OST0000-osc-ffff8d6402f5b800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 536.642714] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 14:00:23 (1772305223) [ 536.696669] LustreError: 37847:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 539.712108] LustreError: 37847:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 awake [ 541.599816] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 542.099432] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 14:00:29 (1772305229) [ 542.669895] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 543.237805] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-Lock-Cancel ========================================================== 14:00:30 (1772305230) [ 543.759729] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 544.375760] Lustre: DEBUG MARKER: == sanityn test 33d: DNE distributed operation should trigger COS ========================================================== 14:00:31 (1772305231) [ 544.843480] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 545.454463] Lustre: DEBUG MARKER: == sanityn test 33e: DNE local operation shouldn't trigger COS ========================================================== 14:00:32 (1772305232) [ 546.008623] Lustre: DEBUG MARKER: SKIP: sanityn test_33e Need two or more clients, have 1 [ 546.708161] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 14:00:33 (1772305233) [ 585.143112] Lustre: lustre-OST0001-osc-ffff8d6402f5b800: Connection to lustre-OST0001 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 585.143112] Lustre: lustre-OST0001-osc-ffff8d6418e92800: Connection to lustre-OST0001 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 585.147751] LustreError: lustre-OST0001-osc-ffff8d6402f5b800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 585.158903] LustreError: lustre-OST0001-osc-ffff8d6418e92800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 585.159543] Lustre: lustre-OST0001-osc-ffff8d6402f5b800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 585.164845] Lustre: Skipped 1 previous similar message [ 595.382972] Lustre: lustre-OST0000-osc-ffff8d6418e92800: Connection to lustre-OST0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 595.391064] LustreError: lustre-OST0000-osc-ffff8d6418e92800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 595.397945] Lustre: lustre-OST0000-osc-ffff8d6418e92800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 606.578097] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8d6402f5b800.ost_server_uuid,osc.lustre-OST0000-osc-ffff8d6418e92800.ost_server_uuid 40 [ 606.688329] Lustre: lustre-OST0001-osc-ffff8d6418e92800: disconnect after 22s idle [ 606.690888] Lustre: Skipped 2 previous similar messages [ 607.134326] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d6402f5b800.ost_server_uuid in FULL state after 0 sec [ 607.694411] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d6418e92800.ost_server_uuid in FULL state after 0 sec [ 610.215774] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d6402f5b800.ost_server_uuid,osc.lustre-OST0001-osc-ffff8d6418e92800.ost_server_uuid 40 [ 610.901872] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d6402f5b800.ost_server_uuid in IDLE state after 0 sec [ 611.535472] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d6418e92800.ost_server_uuid in IDLE state after 0 sec [ 614.028837] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8d6402f5b800.ost_server_uuid,osc.lustre-OST0000-osc-ffff8d6418e92800.ost_server_uuid 40 [ 614.493521] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d6402f5b800.ost_server_uuid in FULL state after 0 sec [ 614.971304] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d6418e92800.ost_server_uuid in FULL state after 0 sec [ 616.870905] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d6402f5b800.ost_server_uuid,osc.lustre-OST0001-osc-ffff8d6418e92800.ost_server_uuid 40 [ 617.388805] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d6402f5b800.ost_server_uuid in IDLE state after 0 sec [ 617.924414] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d6418e92800.ost_server_uuid in IDLE state after 0 sec [ 621.794616] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8d6402f5b800.ost_server_uuid,osc.lustre-OST0000-osc-ffff8d6418e92800.ost_server_uuid 40 [ 622.306782] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d6402f5b800.ost_server_uuid in FULL state after 0 sec [ 622.825503] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d6418e92800.ost_server_uuid in IDLE state after 0 sec [ 624.689435] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d6402f5b800.ost_server_uuid,osc.lustre-OST0001-osc-ffff8d6418e92800.ost_server_uuid 40 [ 625.156924] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d6402f5b800.ost_server_uuid in IDLE state after 0 sec [ 625.640904] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d6418e92800.ost_server_uuid in IDLE state after 0 sec [ 626.161752] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 14:01:53 (1772305313) [ 626.993487] Lustre: DEBUG MARKER: Race attempt 0 [ 628.036728] Lustre: *** cfs_fail_loc=317, val=2147484440*** [ 628.039327] LustreError: 44756:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 318 sleeping [ 628.039678] LustreError: 5007:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 318 waking [ 628.044210] LustreError: 44756:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 318 awake: rc=4997 [ 628.047762] LustreError: 44756:0:(mdc_request.c:1484:mdc_read_page()) lustre-MDT0000-mdc-ffff8d6418e92800: [0x200000401:0x5d:0x0] lock enqueue fails: rc = -4 [ 628.509698] Lustre: DEBUG MARKER: Wait for 44625 44756 for 60 sec... [ 690.856317] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 14:02:57 (1772305377) [ 697.059302] Lustre: DEBUG MARKER: start test - cycle (0) [ 718.857907] Lustre: DEBUG MARKER: start test - cycle (1) [ 738.999970] Lustre: DEBUG MARKER: start test - cycle (2) [ 764.928273] Lustre: DEBUG MARKER: start test - cycle (3) [ 765.408210] Lustre: lustre-OST0001-osc-ffff8d6402f5b800: disconnect after 20s idle [ 765.410646] Lustre: Skipped 2 previous similar messages [ 784.598142] Lustre: DEBUG MARKER: start test - cycle (4) [ 805.803065] Lustre: DEBUG MARKER: start test - cycle (5) [ 826.749496] Lustre: DEBUG MARKER: start test - cycle (6) [ 846.417424] Lustre: DEBUG MARKER: start test - cycle (7) [ 871.812446] Lustre: DEBUG MARKER: start test - cycle (8) [ 872.928197] Lustre: lustre-OST0001-osc-ffff8d6402f5b800: disconnect after 20s idle [ 892.850925] Lustre: DEBUG MARKER: start test - cycle (9) [ 912.604561] Lustre: DEBUG MARKER: start test - cycle (10) [ 935.680125] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 14:07:02 (1772305622) [ 939.488173] Lustre: lustre-OST0001-osc-ffff8d6402f5b800: disconnect after 21s idle [ 949.747986] Lustre: DEBUG MARKER: == sanityn test 39a: test from 11063 ============================================================================================ 14:07:16 (1772305636) [ 952.185670] Lustre: DEBUG MARKER: == sanityn test 39b: 11063 problem 1 ============================================================================================ 14:07:19 (1772305639) [ 955.779361] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 14:07:22 (1772305642) [ 958.965577] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 14:07:26 (1772305646) [ 959.028650] Lustre: *** cfs_fail_loc=411, val=0*** [ 961.110730] Lustre: DEBUG MARKER: == sanityn test 40a: pdirops: create vs others ======================================================================== 14:07:28 (1772305648) [ 969.738055] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 14:07:36 (1772305656) [ 978.388418] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 14:07:45 (1772305665) [ 986.703510] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 14:07:53 (1772305673) [ 995.106751] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 14:08:02 (1772305682) [ 1003.114696] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 14:08:10 (1772305690) [ 1009.635543] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 14:08:16 (1772305696) [ 1016.501675] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 14:08:23 (1772305703) [ 1021.979501] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 14:08:29 (1772305709) [ 1027.718441] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 14:08:34 (1772305714) [ 1033.067345] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 14:08:40 (1772305720) [ 1039.831846] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 14:08:46 (1772305726) [ 1047.134891] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 14:08:54 (1772305734) [ 1053.154250] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 14:09:00 (1772305740) [ 1072.608272] Lustre: lustre-OST0000-osc-ffff8d6418e92800: disconnect after 21s idle [ 1072.612178] Lustre: Skipped 4 previous similar messages [ 1797.803896] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 14:21:24 (1772306484) [ 1803.458610] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 14:21:30 (1772306490) [ 1809.276053] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 14:21:36 (1772306496) [ 1814.670626] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 14:21:41 (1772306501) [ 1820.797567] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 14:21:47 (1772306507) [ 1826.845445] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 14:21:53 (1772306513) [ 1832.622033] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 14:21:59 (1772306519) [ 1835.488193] Lustre: lustre-OST0001-osc-ffff8d6418e92800: disconnect after 21s idle [ 1838.286102] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 14:22:05 (1772306525) [ 1844.357043] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 14:22:11 (1772306531) [ 1859.161243] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 14:22:26 (1772306546) [ 1864.536361] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 14:22:31 (1772306551) [ 1869.715658] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 14:22:36 (1772306556) [ 1875.424109] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 14:22:42 (1772306562) [ 1881.542841] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 14:22:48 (1772306568) [ 1887.250182] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 14:22:54 (1772306574) [ 1893.783072] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 14:23:00 (1772306580) [ 1899.618730] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 14:23:06 (1772306586) [ 1900.142628] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 1900.707150] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 14:23:07 (1772306587) [ 1949.407933] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 14:23:56 (1772306636) [ 2400.229356] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 14:31:27 (1772307087) [ 2405.318992] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 14:31:32 (1772307092) [ 2410.383499] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 14:31:37 (1772307097) [ 2415.383385] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 14:31:42 (1772307102) [ 2420.553884] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 14:31:47 (1772307107) [ 2425.647279] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 14:31:52 (1772307112) [ 2430.817804] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 14:31:57 (1772307117) [ 2436.091745] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 14:32:03 (1772307123) [ 2441.116401] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 14:32:08 (1772307128) [ 2441.630299] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 2442.185961] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 14:32:09 (1772307129) [ 2455.008115] Lustre: lustre-OST0001-osc-ffff8d6418e92800: disconnect after 21s idle [ 2455.010516] Lustre: Skipped 4 previous similar messages [ 2465.359954] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 14:32:32 (1772307152) [ 2470.311505] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 14:32:37 (1772307157) [ 2475.309873] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 14:32:42 (1772307162) [ 2480.158144] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 14:32:47 (1772307167) [ 2485.002514] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 14:32:52 (1772307172) [ 2489.978207] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 14:32:57 (1772307177) [ 2494.886101] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 14:33:01 (1772307181) [ 2499.379209] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 14:33:06 (1772307186) [ 2499.837422] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 2500.341674] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 14:33:07 (1772307187) [ 2962.989406] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 14:40:50 (1772307650) [ 2969.319576] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 14:40:56 (1772307656) [ 2975.913964] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 14:41:03 (1772307663) [ 2982.362468] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 14:41:09 (1772307669) [ 2989.150671] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 14:41:16 (1772307676) [ 2995.883467] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 14:41:22 (1772307682) [ 3002.177472] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 14:41:29 (1772307689) [ 3008.169841] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 14:41:35 (1772307695) [ 3014.833315] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 14:41:41 (1772307701) [ 3015.383977] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 3016.243938] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 14:41:43 (1772307703) [ 3016.924911] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 3017.601559] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 14:41:44 (1772307704) [ 3018.342397] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 3019.223799] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 14:41:46 (1772307706) [ 3020.034782] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 3020.875194] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 14:41:47 (1772307707) [ 3021.571091] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 3022.469230] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 14:41:49 (1772307709) [ 3023.304675] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 3024.199689] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 14:41:50 (1772307710) [ 3024.934442] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 3025.857029] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 14:41:52 (1772307712) [ 3026.591993] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 3027.378423] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 14:41:54 (1772307714) [ 3027.512523] LustreError: 5008:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3029.592068] LustreError: 5008:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 awake [ 3035.072814] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 14:42:01 (1772307721) [ 3039.384092] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 14:42:06 (1772307726) [ 3039.526694] LustreError: 243327:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3043.584111] LustreError: 243327:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3043.590646] LustreError: 243327:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3047.648120] LustreError: 243327:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3047.670405] LustreError: 243333:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3051.736141] LustreError: 243333:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3055.179980] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 14:42:21 (1772307741) [ 3062.697771] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 14:42:29 (1772307749) [ 3066.546940] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 14:42:33 (1772307753) [ 3071.083578] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 14:42:38 (1772307758) [ 3074.528190] Lustre: lustre-OST0001-osc-ffff8d6418e92800: disconnect after 21s idle [ 3074.530601] Lustre: Skipped 7 previous similar messages [ 3096.122084] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 14:43:03 (1772307783) [ 3104.473770] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 14:43:11 (1772307791) [ 3112.837565] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 14:43:19 (1772307799) [ 3125.677675] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 14:43:32 (1772307812) [ 3135.642067] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 14:43:42 (1772307822) [ 3138.392096] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3141.679366] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 14:43:48 (1772307828) [ 3144.810562] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 14:43:51 (1772307831) [ 3145.511583] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3146.376702] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 14:43:53 (1772307833) [ 3147.098650] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3148.010624] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 14:43:54 (1772307834) [ 3148.826801] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 3149.719462] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 14:43:56 (1772307836) [ 3150.345150] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3151.154314] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 14:43:58 (1772307838) [ 3154.161739] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 14:44:00 (1772307840) [ 3156.932531] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 14:44:04 (1772307844) [ 3160.002631] LustreError: 11-0: lustre-MDT0000-mdc-ffff8d6418e92800: operation ldlm_enqueue to node 192.168.202.129@tcp failed: rc = -35 [ 3160.007039] LustreError: Skipped 1 previous similar message [ 3163.092798] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 14:44:10 (1772307850) [ 3163.229421] LustreError: 2238:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3165.312153] LustreError: 2238:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d awake [ 3171.106461] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 14:44:17 (1772307857) [ 3195.415911] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 14:44:42 (1772307882) [ 3199.007443] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 14:44:45 (1772307885) [ 3203.578677] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 14:44:50 (1772307890) [ 3210.339979] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 14:44:57 (1772307897) [ 3215.821064] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 14:45:02 (1772307902) [ 3225.807768] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 14:45:12 (1772307912) [ 3235.659060] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 14:45:22 (1772307922) [ 3240.890176] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 14:45:27 (1772307927) [ 3245.917541] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 14:45:33 (1772307933) [ 3255.828779] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 14:45:42 (1772307942) [ 3308.288452] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 14:46:35 (1772307995) [ 3444.601194] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with NID/JobID/OPCode expression ========================================================== 14:48:51 (1772308131) [ 3714.528227] Lustre: lustre-OST0001-osc-ffff8d6418e92800: disconnect after 21s idle [ 3714.530582] Lustre: Skipped 14 previous similar messages [ 3810.977917] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 14:54:57 (1772308497) [ 3814.884349] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 14:55:01 (1772308501) [ 3866.216667] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 14:55:53 (1772308553) [ 3932.801244] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 14:56:59 (1772308619) [ 3937.189540] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 14:57:04 (1772308624) [ 3983.966963] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 14:57:50 (1772308670) [ 4000.005572] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 14:58:06 (1772308686) [ 4003.501282] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 14:58:10 (1772308690) [ 4016.130687] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 14:58:23 (1772308703) [ 4016.895553] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 4017.550376] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 14:58:24 (1772308704) [ 4018.011787] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 4018.538213] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 14:58:25 (1772308705) [ 4019.225438] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 4020.035673] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 14:58:26 (1772308706) [ 4020.620029] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 4021.412308] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 14:58:28 (1772308708) [ 4022.068431] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 4022.656922] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 14:58:29 (1772308709) [ 4025.281408] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 14:58:32 (1772308712) [ 4026.056589] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 4026.893739] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 14:58:33 (1772308713) [ 4035.779904] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 14:58:42 (1772308722) [ 4036.580811] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 4037.499832] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 14:58:44 (1772308724) [ 4038.347717] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 4039.216526] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 14:58:46 (1772308726) [ 4039.815964] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 4040.648707] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 14:58:47 (1772308727) [ 4051.307719] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 14:58:58 (1772308738) [ 4051.431828] Lustre: DEBUG MARKER: write [ 4051.452536] LustreError: 5015:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 sleeping for 5000ms [ 4053.464169] Lustre: DEBUG MARKER: kill 284118 [ 4053.468140] LustreError: 284118:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 sleeping for 6000ms [ 4056.552149] LustreError: 5015:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 awake [ 4059.504137] LustreError: 284118:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 awake [ 4062.933914] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 14:59:09 (1772308749) [ 4063.101689] LustreError: 284708:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 4065.184117] LustreError: 284708:0:(fail.c:144:__cfs_fail_timeout_set()) cfs_fail_timeout interrupted [ 4072.031552] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 14:59:18 (1772308758) [ 4072.791756] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 4073.621647] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 14:59:20 (1772308760) [ 4075.974432] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 14:59:23 (1772308763) [ 4077.947503] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 14:59:25 (1772308765) [ 4080.274226] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 14:59:27 (1772308767) [ 4083.457886] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 14:59:30 (1772308770) [ 4086.435992] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 14:59:33 (1772308773) [ 4089.163274] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 14:59:36 (1772308776) [ 4092.942751] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 4093.638615] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 14:59:40 (1772308780) [ 4094.403941] Lustre: *** cfs_fail_loc=415, val=0*** [ 4101.938491] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 14:59:48 (1772308788) [ 4102.613128] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 4103.389976] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 14:59:50 (1772308790) [ 4103.491538] LustreError: 5016:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 4108.592115] LustreError: 5016:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 4113.696107] LustreError: 5016:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 4113.699176] LustreError: 5016:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 4113.702275] LustreError: 5016:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 4123.896120] LustreError: 5016:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 4123.898837] LustreError: 5016:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 4134.098052] LustreError: 5016:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 4134.100461] LustreError: 5016:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 4144.296102] LustreError: 5015:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 4144.298416] LustreError: 5015:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 4151.811285] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 15:00:38 (1772308838) [ 4152.293988] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 4152.850820] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 15:00:39 (1772308839) [ 4155.103593] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 15:00:42 (1772308842) [ 4157.217936] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 15:00:44 (1772308844) [ 4161.364932] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 15:00:48 (1772308848) [ 4169.754331] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 15:00:56 (1772308856) [ 4169.864748] LustreError: 2240:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 414 sleeping for 4000ms [ 4169.866739] LustreError: 2240:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 2 previous similar messages [ 4176.426502] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 15:01:03 (1772308863) [ 4177.131544] Lustre: Unmounted lustre-client [ 4177.768133] Lustre: Unmounted lustre-client [ 4178.240232] Lustre: DEBUG MARKER: Iteration 1 [ 4178.397704] LustreError: 294755:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4178.399755] LustreError: 294756:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4178.403631] LustreError: 294755:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4178.448703] Lustre: Mounted lustre-client [ 4178.449805] Lustre: Skipped 1 previous similar message [ 4178.961335] Lustre: Unmounted lustre-client [ 4178.962575] Lustre: Skipped 1 previous similar message [ 4180.017695] Key type lgssc unregistered [ 4180.129454] LNet: 295104:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4181.153125] LNet: Removed LNI 192.168.202.29@tcp [ 4181.431074] Key type .llcrypt unregistered [ 4181.432074] Key type ._llcrypt unregistered [ 4181.715374] alg: No test for adler32 (adler32-zlib) [ 4182.468360] Key type ._llcrypt registered [ 4182.469917] Key type .llcrypt registered [ 4182.589481] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4182.798221] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4183.042498] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4183.043959] LNet: Accept secure, port 988 [ 4184.648113] Key type lgssc registered [ 4185.130244] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4189.296568] Lustre: DEBUG MARKER: Iteration 2 [ 4189.398318] LustreError: 295888:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4189.399772] LustreError: 295889:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4189.404418] LustreError: 295888:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 4190.471104] Lustre: Mounted lustre-client [ 4190.472899] Lustre: Skipped 1 previous similar message [ 4191.087339] Lustre: Unmounted lustre-client [ 4192.122533] Key type lgssc unregistered [ 4192.240533] LNet: 296236:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4193.248499] LNet: Removed LNI 192.168.202.29@tcp [ 4193.500065] Key type .llcrypt unregistered [ 4193.501885] Key type ._llcrypt unregistered [ 4193.800356] alg: No test for adler32 (adler32-zlib) [ 4194.558339] Key type ._llcrypt registered [ 4194.559233] Key type .llcrypt registered [ 4194.629175] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4194.744168] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4194.831832] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4194.833443] LNet: Accept secure, port 988 [ 4196.424124] Key type lgssc registered [ 4196.863427] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4200.209858] Lustre: DEBUG MARKER: Iteration 3 [ 4200.310032] LustreError: 297020:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4200.310148] LustreError: 297021:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4200.318079] LustreError: 297020:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4201.379686] Lustre: Mounted lustre-client [ 4201.381627] Lustre: Skipped 1 previous similar message [ 4201.886520] Lustre: Unmounted lustre-client [ 4202.859569] Key type lgssc unregistered [ 4202.955460] LNet: 297370:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4204.000783] LNet: Removed LNI 192.168.202.29@tcp [ 4204.214850] Key type .llcrypt unregistered [ 4204.215810] Key type ._llcrypt unregistered [ 4204.507671] alg: No test for adler32 (adler32-zlib) [ 4205.258381] Key type ._llcrypt registered [ 4205.259717] Key type .llcrypt registered [ 4205.327436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4205.438687] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4205.520770] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4205.522203] LNet: Accept secure, port 988 [ 4207.104120] Key type lgssc registered [ 4207.449339] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4210.651554] Lustre: DEBUG MARKER: Iteration 4 [ 4210.764289] LustreError: 298154:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4210.771380] LustreError: 298155:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4210.773583] LustreError: 298154:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 4211.821762] Lustre: Mounted lustre-client [ 4211.823213] Lustre: Skipped 1 previous similar message [ 4212.290757] Lustre: Unmounted lustre-client [ 4213.223360] Key type lgssc unregistered [ 4213.325399] LNet: 298508:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4214.368773] LNet: Removed LNI 192.168.202.29@tcp [ 4214.607496] Key type .llcrypt unregistered [ 4214.609411] Key type ._llcrypt unregistered [ 4214.861563] alg: No test for adler32 (adler32-zlib) [ 4215.617350] Key type ._llcrypt registered [ 4215.618271] Key type .llcrypt registered [ 4215.698520] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4215.825748] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4215.914261] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4215.917011] LNet: Accept secure, port 988 [ 4217.504117] Key type lgssc registered [ 4217.893488] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4221.270823] Lustre: DEBUG MARKER: Iteration 5 [ 4221.372153] LustreError: 299291:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4221.372203] LustreError: 299292:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4221.378644] LustreError: 299291:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4222.416753] Lustre: Mounted lustre-client [ 4222.418291] Lustre: Skipped 1 previous similar message [ 4222.881222] Lustre: Unmounted lustre-client [ 4223.890800] Key type lgssc unregistered [ 4223.992501] LNet: 299646:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4225.056745] LNet: Removed LNI 192.168.202.29@tcp [ 4225.294567] Key type .llcrypt unregistered [ 4225.296160] Key type ._llcrypt unregistered [ 4225.545820] alg: No test for adler32 (adler32-zlib) [ 4226.297465] Key type ._llcrypt registered [ 4226.299056] Key type .llcrypt registered [ 4226.378764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4226.478542] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4226.550717] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4226.552290] LNet: Accept secure, port 988 [ 4228.136140] Key type lgssc registered [ 4228.538593] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4231.754182] Lustre: DEBUG MARKER: Iteration 6 [ 4231.847078] LustreError: 300429:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4231.847283] LustreError: 300430:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4231.854180] LustreError: 300429:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4232.906453] Lustre: Mounted lustre-client [ 4232.907474] Lustre: Skipped 1 previous similar message [ 4233.391823] Lustre: Unmounted lustre-client [ 4233.392813] Lustre: Skipped 1 previous similar message [ 4234.354873] Key type lgssc unregistered [ 4234.466658] LNet: 300785:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4235.489686] LNet: Removed LNI 192.168.202.29@tcp [ 4235.748322] Key type .llcrypt unregistered [ 4235.749403] Key type ._llcrypt unregistered [ 4236.125864] alg: No test for adler32 (adler32-zlib) [ 4236.877372] Key type ._llcrypt registered [ 4236.878438] Key type .llcrypt registered [ 4236.940684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4237.053045] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4237.125284] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4237.127436] LNet: Accept secure, port 988 [ 4238.712133] Key type lgssc registered [ 4239.081776] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4242.603085] Lustre: DEBUG MARKER: Iteration 7 [ 4242.725648] LustreError: 301567:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4242.729777] LustreError: 301569:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4242.735777] LustreError: 301567:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 4243.788412] Lustre: Mounted lustre-client [ 4243.789559] Lustre: Skipped 1 previous similar message [ 4244.465549] Lustre: Unmounted lustre-client [ 4245.565870] Key type lgssc unregistered [ 4245.679498] LNet: 301920:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4246.689039] LNet: Removed LNI 192.168.202.29@tcp [ 4246.947846] Key type .llcrypt unregistered [ 4246.949989] Key type ._llcrypt unregistered [ 4247.428971] alg: No test for adler32 (adler32-zlib) [ 4248.185358] Key type ._llcrypt registered [ 4248.186394] Key type .llcrypt registered [ 4248.261489] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4248.374526] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4248.477244] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4248.479584] LNet: Accept secure, port 988 [ 4250.072197] Key type lgssc registered [ 4250.471129] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4253.830468] Lustre: DEBUG MARKER: Iteration 8 [ 4253.920496] LustreError: 302703:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4253.920979] LustreError: 302704:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4253.924785] LustreError: 302703:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4254.968929] Lustre: Mounted lustre-client [ 4254.970476] Lustre: Skipped 1 previous similar message [ 4255.543214] Lustre: Unmounted lustre-client [ 4256.489349] Key type lgssc unregistered [ 4256.582267] LNet: 303056:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4257.632817] LNet: Removed LNI 192.168.202.29@tcp [ 4257.852152] Key type .llcrypt unregistered [ 4257.853504] Key type ._llcrypt unregistered [ 4258.124823] alg: No test for adler32 (adler32-zlib) [ 4258.879365] Key type ._llcrypt registered [ 4258.880372] Key type .llcrypt registered [ 4258.952343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4259.052614] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4259.124923] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4259.126375] LNet: Accept secure, port 988 [ 4260.704109] Key type lgssc registered [ 4261.051171] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4264.658248] Lustre: DEBUG MARKER: Iteration 9 [ 4264.778931] LustreError: 303839:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4264.780205] LustreError: 303840:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4264.785889] LustreError: 303839:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4265.837936] Lustre: Mounted lustre-client [ 4265.838858] Lustre: Skipped 1 previous similar message [ 4266.340572] Lustre: Unmounted lustre-client [ 4267.333502] Key type lgssc unregistered [ 4267.432458] LNet: 304188:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4268.448445] LNet: Removed LNI 192.168.202.29@tcp [ 4268.700486] Key type .llcrypt unregistered [ 4268.702123] Key type ._llcrypt unregistered [ 4269.118110] alg: No test for adler32 (adler32-zlib) [ 4269.869337] Key type ._llcrypt registered [ 4269.870985] Key type .llcrypt registered [ 4269.935408] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4270.035605] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4270.109019] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4270.110723] LNet: Accept secure, port 988 [ 4271.688103] Key type lgssc registered [ 4272.030637] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4275.503969] Lustre: DEBUG MARKER: Iteration 10 [ 4275.596290] LustreError: 304973:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4275.596338] LustreError: 304972:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4275.602401] LustreError: 304973:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 4276.648318] Lustre: Mounted lustre-client [ 4276.649488] Lustre: Skipped 1 previous similar message [ 4277.156728] Lustre: Unmounted lustre-client [ 4278.078921] Key type lgssc unregistered [ 4278.177389] LNet: 305320:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4279.200707] LNet: Removed LNI 192.168.202.29@tcp [ 4279.406519] Key type .llcrypt unregistered [ 4279.407459] Key type ._llcrypt unregistered [ 4279.641241] alg: No test for adler32 (adler32-zlib) [ 4280.393318] Key type ._llcrypt registered [ 4280.394471] Key type .llcrypt registered [ 4280.457645] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4280.559512] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4280.653685] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4280.655226] LNet: Accept secure, port 988 [ 4282.248127] Key type lgssc registered [ 4282.657633] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4285.994885] Lustre: DEBUG MARKER: Iteration 11 [ 4286.083078] LustreError: 306104:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4286.083248] LustreError: 306105:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4286.089152] LustreError: 306104:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4287.128570] Lustre: Mounted lustre-client [ 4287.130276] Lustre: Skipped 1 previous similar message [ 4287.545559] Lustre: Unmounted lustre-client [ 4288.460017] Key type lgssc unregistered [ 4288.561908] LNet: 306458:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4289.568527] LNet: Removed LNI 192.168.202.29@tcp [ 4289.792741] Key type .llcrypt unregistered [ 4289.793639] Key type ._llcrypt unregistered [ 4290.052970] alg: No test for adler32 (adler32-zlib) [ 4290.806296] Key type ._llcrypt registered [ 4290.807155] Key type .llcrypt registered [ 4290.862073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4290.954606] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4291.022925] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4291.024152] LNet: Accept secure, port 988 [ 4292.600109] Key type lgssc registered [ 4292.924892] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4295.940521] Lustre: DEBUG MARKER: Iteration 12 [ 4296.048673] LustreError: 307241:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4296.049051] LustreError: 307242:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4296.055497] LustreError: 307241:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 4297.101284] Lustre: Mounted lustre-client [ 4297.102306] Lustre: Skipped 1 previous similar message [ 4297.549568] Lustre: Unmounted lustre-client [ 4298.410388] Key type lgssc unregistered [ 4298.505440] LNet: 307592:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4299.552635] LNet: Removed LNI 192.168.202.29@tcp [ 4299.763348] Key type .llcrypt unregistered [ 4299.764892] Key type ._llcrypt unregistered [ 4300.071538] alg: No test for adler32 (adler32-zlib) [ 4300.829331] Key type ._llcrypt registered [ 4300.830482] Key type .llcrypt registered [ 4300.901787] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4300.994867] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4301.062726] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4301.065448] LNet: Accept secure, port 988 [ 4302.640080] Key type lgssc registered [ 4302.955316] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4306.087217] Lustre: DEBUG MARKER: Iteration 13 [ 4306.192081] LustreError: 308375:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4306.192316] LustreError: 308376:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4306.197805] LustreError: 308375:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4307.253713] Lustre: Mounted lustre-client [ 4307.254567] Lustre: Skipped 1 previous similar message [ 4307.697853] Lustre: Unmounted lustre-client [ 4308.586193] Key type lgssc unregistered [ 4308.682366] LNet: 308722:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4309.729467] LNet: Removed LNI 192.168.202.29@tcp [ 4309.931496] Key type .llcrypt unregistered [ 4309.932298] Key type ._llcrypt unregistered [ 4310.173961] alg: No test for adler32 (adler32-zlib) [ 4310.925245] Key type ._llcrypt registered [ 4310.926599] Key type .llcrypt registered [ 4310.986092] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4311.075407] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4311.140993] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4311.142770] LNet: Accept secure, port 988 [ 4312.712132] Key type lgssc registered [ 4313.074847] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4316.232131] Lustre: DEBUG MARKER: Iteration 14 [ 4316.326986] LustreError: 309506:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4316.327088] LustreError: 309507:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4316.331069] LustreError: 309506:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4317.369155] Lustre: Mounted lustre-client [ 4317.370303] Lustre: Skipped 1 previous similar message [ 4317.934978] Lustre: Unmounted lustre-client [ 4318.905299] Key type lgssc unregistered [ 4319.005311] LNet: 309853:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4320.032662] LNet: Removed LNI 192.168.202.29@tcp [ 4320.245850] Key type .llcrypt unregistered [ 4320.247662] Key type ._llcrypt unregistered [ 4320.501886] alg: No test for adler32 (adler32-zlib) [ 4321.255389] Key type ._llcrypt registered [ 4321.256504] Key type .llcrypt registered [ 4321.318506] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4321.405260] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4321.478133] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4321.479777] LNet: Accept secure, port 988 [ 4323.048108] Key type lgssc registered [ 4323.378345] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4326.737946] Lustre: DEBUG MARKER: Iteration 15 [ 4326.840808] LustreError: 310636:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4326.840861] LustreError: 310637:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4326.848766] LustreError: 310636:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4327.904443] Lustre: Mounted lustre-client [ 4327.905409] Lustre: Skipped 1 previous similar message [ 4328.378825] Lustre: Unmounted lustre-client [ 4329.240802] Key type lgssc unregistered [ 4329.335462] LNet: 310984:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4330.400652] LNet: Removed LNI 192.168.202.29@tcp [ 4330.609683] Key type .llcrypt unregistered [ 4330.610881] Key type ._llcrypt unregistered [ 4330.891040] alg: No test for adler32 (adler32-zlib) [ 4331.642312] Key type ._llcrypt registered [ 4331.643460] Key type .llcrypt registered [ 4331.702268] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4331.787266] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4331.856349] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4331.858792] LNet: Accept secure, port 988 [ 4333.440107] Key type lgssc registered [ 4333.774760] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4336.871648] Lustre: DEBUG MARKER: Iteration 16 [ 4336.967697] LustreError: 311767:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4336.967728] LustreError: 311768:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4336.973147] LustreError: 311767:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4338.015650] Lustre: Mounted lustre-client [ 4338.467783] Lustre: Unmounted lustre-client [ 4339.354993] Key type lgssc unregistered [ 4339.449427] LNet: 312115:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4340.512764] LNet: Removed LNI 192.168.202.29@tcp [ 4340.707448] Key type .llcrypt unregistered [ 4340.708494] Key type ._llcrypt unregistered [ 4341.046623] alg: No test for adler32 (adler32-zlib) [ 4341.798416] Key type ._llcrypt registered [ 4341.799893] Key type .llcrypt registered [ 4341.862347] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4341.953944] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4342.021344] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4342.022570] LNet: Accept secure, port 988 [ 4343.600053] Key type lgssc registered [ 4343.925105] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4347.125931] Lustre: DEBUG MARKER: Iteration 17 [ 4347.214095] LustreError: 312898:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4347.214114] LustreError: 312900:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4347.219921] LustreError: 312898:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4999 [ 4348.260329] Lustre: Mounted lustre-client [ 4348.784164] Lustre: Unmounted lustre-client [ 4349.712419] Key type lgssc unregistered [ 4349.799720] LNet: 313249:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4350.816798] LNet: Removed LNI 192.168.202.29@tcp [ 4351.020649] Key type .llcrypt unregistered [ 4351.022428] Key type ._llcrypt unregistered [ 4351.289758] alg: No test for adler32 (adler32-zlib) [ 4352.040391] Key type ._llcrypt registered [ 4352.041380] Key type .llcrypt registered [ 4352.116823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4352.213925] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4352.289491] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4352.291602] LNet: Accept secure, port 988 [ 4353.880140] Key type lgssc registered [ 4354.213439] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4357.343818] Lustre: DEBUG MARKER: Iteration 18 [ 4357.439301] LustreError: 314032:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4357.441069] LustreError: 314034:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4357.446530] LustreError: 314032:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 4358.485839] Lustre: Mounted lustre-client [ 4358.486961] Lustre: Skipped 1 previous similar message [ 4358.923278] Lustre: Unmounted lustre-client [ 4358.924247] Lustre: Skipped 1 previous similar message [ 4359.759379] Key type lgssc unregistered [ 4359.852467] LNet: 314384:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4360.864890] LNet: Removed LNI 192.168.202.29@tcp [ 4361.069547] Key type .llcrypt unregistered [ 4361.071115] Key type ._llcrypt unregistered [ 4361.329733] alg: No test for adler32 (adler32-zlib) [ 4362.081281] Key type ._llcrypt registered [ 4362.082050] Key type .llcrypt registered [ 4362.135321] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4362.237634] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4362.321533] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4362.323134] LNet: Accept secure, port 988 [ 4363.904127] Key type lgssc registered [ 4364.252216] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4367.638111] Lustre: DEBUG MARKER: Iteration 19 [ 4367.740499] LustreError: 315167:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4367.740556] LustreError: 315168:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4367.744887] LustreError: 315167:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4368.804940] Lustre: Mounted lustre-client [ 4369.299142] Lustre: Unmounted lustre-client [ 4370.131518] Key type lgssc unregistered [ 4370.223464] LNet: 315520:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4371.232657] LNet: Removed LNI 192.168.202.29@tcp [ 4371.447329] Key type .llcrypt unregistered [ 4371.448693] Key type ._llcrypt unregistered [ 4371.685988] alg: No test for adler32 (adler32-zlib) [ 4372.437313] Key type ._llcrypt registered [ 4372.438294] Key type .llcrypt registered [ 4372.504658] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4372.599670] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4372.671828] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4372.674040] LNet: Accept secure, port 988 [ 4374.256070] Key type lgssc registered [ 4374.591546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4377.722836] Lustre: DEBUG MARKER: Iteration 20 [ 4377.806550] LustreError: 316304:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4377.806607] LustreError: 316305:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4377.810656] LustreError: 316304:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4378.851684] Lustre: Mounted lustre-client [ 4379.313505] Lustre: Unmounted lustre-client [ 4380.161815] Key type lgssc unregistered [ 4380.253257] LNet: 316661:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4381.280645] LNet: Removed LNI 192.168.202.29@tcp [ 4381.481867] Key type .llcrypt unregistered [ 4381.482745] Key type ._llcrypt unregistered [ 4381.729808] alg: No test for adler32 (adler32-zlib) [ 4382.480309] Key type ._llcrypt registered [ 4382.481317] Key type .llcrypt registered [ 4382.546019] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4382.643662] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4382.710996] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4382.712324] LNet: Accept secure, port 988 [ 4384.288107] Key type lgssc registered [ 4384.613365] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4387.662194] Lustre: DEBUG MARKER: Iteration 21 [ 4387.751617] LustreError: 317445:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4387.751820] LustreError: 317446:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4387.757147] LustreError: 317445:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4388.790547] Lustre: Mounted lustre-client [ 4388.792332] Lustre: Skipped 1 previous similar message [ 4389.289189] Lustre: Unmounted lustre-client [ 4389.290651] Lustre: Skipped 1 previous similar message [ 4390.119170] Key type lgssc unregistered [ 4390.206344] LNet: 317796:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4391.264758] LNet: Removed LNI 192.168.202.29@tcp [ 4391.491590] Key type .llcrypt unregistered [ 4391.492618] Key type ._llcrypt unregistered [ 4391.760677] alg: No test for adler32 (adler32-zlib) [ 4392.512327] Key type ._llcrypt registered [ 4392.513166] Key type .llcrypt registered [ 4392.590679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4392.694172] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4392.771329] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4392.772679] LNet: Accept secure, port 988 [ 4394.360108] Key type lgssc registered [ 4394.729629] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4397.727465] Lustre: DEBUG MARKER: Iteration 22 [ 4397.814921] LustreError: 318579:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4397.814950] LustreError: 318581:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4397.821358] LustreError: 318579:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4398.862129] Lustre: Mounted lustre-client [ 4398.863201] Lustre: Skipped 1 previous similar message [ 4399.376160] Lustre: Unmounted lustre-client [ 4399.377151] Lustre: Skipped 1 previous similar message [ 4400.201784] Key type lgssc unregistered [ 4400.293417] LNet: 318934:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4401.312648] LNet: Removed LNI 192.168.202.29@tcp [ 4401.519934] Key type .llcrypt unregistered [ 4401.521249] Key type ._llcrypt unregistered [ 4401.756309] alg: No test for adler32 (adler32-zlib) [ 4402.507333] Key type ._llcrypt registered [ 4402.509046] Key type .llcrypt registered [ 4402.570955] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4402.665809] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4402.738103] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4402.740266] LNet: Accept secure, port 988 [ 4404.320073] Key type lgssc registered [ 4404.623757] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4407.918727] Lustre: DEBUG MARKER: Iteration 23 [ 4408.012789] LustreError: 319718:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4408.013882] LustreError: 319719:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4408.018211] LustreError: 319718:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4409.063436] Lustre: Mounted lustre-client [ 4409.064365] Lustre: Skipped 1 previous similar message [ 4409.526111] Lustre: Unmounted lustre-client [ 4410.340482] Key type lgssc unregistered [ 4410.438538] LNet: 320067:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4411.488967] LNet: Removed LNI 192.168.202.29@tcp [ 4411.697690] Key type .llcrypt unregistered [ 4411.698668] Key type ._llcrypt unregistered [ 4412.024040] alg: No test for adler32 (adler32-zlib) [ 4412.778257] Key type ._llcrypt registered [ 4412.779127] Key type .llcrypt registered [ 4412.842809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4412.940096] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4413.010700] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4413.012385] LNet: Accept secure, port 988 [ 4414.592125] Key type lgssc registered [ 4414.936696] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4418.049507] Lustre: DEBUG MARKER: Iteration 24 [ 4418.141924] LustreError: 320850:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4418.142080] LustreError: 320851:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4418.147823] LustreError: 320850:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4999 [ 4419.191116] Lustre: Mounted lustre-client [ 4419.192389] Lustre: Skipped 1 previous similar message [ 4419.669070] Lustre: Unmounted lustre-client [ 4419.670628] Lustre: Skipped 1 previous similar message [ 4420.519198] Key type lgssc unregistered [ 4420.608411] LNet: 321203:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4421.664698] LNet: Removed LNI 192.168.202.29@tcp [ 4421.878404] Key type .llcrypt unregistered [ 4421.879859] Key type ._llcrypt unregistered [ 4422.186079] alg: No test for adler32 (adler32-zlib) [ 4422.937317] Key type ._llcrypt registered [ 4422.938711] Key type .llcrypt registered [ 4423.003591] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4423.100714] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4423.177126] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4423.180050] LNet: Accept secure, port 988 [ 4424.760100] Key type lgssc registered [ 4425.089556] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4428.330095] Lustre: DEBUG MARKER: Iteration 25 [ 4428.419658] LustreError: 321987:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4428.419694] LustreError: 321988:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4428.425395] LustreError: 321987:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4429.465440] Lustre: Mounted lustre-client [ 4429.466909] Lustre: Skipped 1 previous similar message [ 4429.922325] Lustre: Unmounted lustre-client [ 4429.923534] Lustre: Skipped 1 previous similar message [ 4430.750576] Key type lgssc unregistered [ 4430.851304] LNet: 322341:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4431.904711] LNet: Removed LNI 192.168.202.29@tcp [ 4432.123028] Key type .llcrypt unregistered [ 4432.124675] Key type ._llcrypt unregistered [ 4432.359138] alg: No test for adler32 (adler32-zlib) [ 4433.110368] Key type ._llcrypt registered [ 4433.111787] Key type .llcrypt registered [ 4433.175943] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4433.271331] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4433.341713] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4433.343729] LNet: Accept secure, port 988 [ 4434.920097] Key type lgssc registered [ 4435.232550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4438.219741] Lustre: DEBUG MARKER: Iteration 26 [ 4438.316220] LustreError: 323124:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4438.316262] LustreError: 323126:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4438.322982] LustreError: 323124:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4439.356967] Lustre: Mounted lustre-client [ 4439.793682] Lustre: Unmounted lustre-client [ 4440.599234] Key type lgssc unregistered [ 4440.709431] LNet: 323475:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4441.760745] LNet: Removed LNI 192.168.202.29@tcp [ 4441.962446] Key type .llcrypt unregistered [ 4441.963467] Key type ._llcrypt unregistered [ 4442.190862] alg: No test for adler32 (adler32-zlib) [ 4442.942313] Key type ._llcrypt registered [ 4442.943257] Key type .llcrypt registered [ 4442.999318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4443.093149] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4443.158103] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4443.159544] LNet: Accept secure, port 988 [ 4444.736126] Key type lgssc registered [ 4445.086716] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4448.400806] Lustre: DEBUG MARKER: Iteration 27 [ 4448.507273] LustreError: 324258:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4448.507333] LustreError: 324259:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4448.514158] LustreError: 324258:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 4449.561236] Lustre: Mounted lustre-client [ 4450.087652] Lustre: Unmounted lustre-client [ 4450.938836] Key type lgssc unregistered [ 4451.029466] LNet: 324612:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4452.064746] LNet: Removed LNI 192.168.202.29@tcp [ 4452.259785] Key type .llcrypt unregistered [ 4452.260811] Key type ._llcrypt unregistered [ 4452.514685] alg: No test for adler32 (adler32-zlib) [ 4453.266316] Key type ._llcrypt registered [ 4453.267471] Key type .llcrypt registered [ 4453.330570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4453.418521] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4453.487725] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4453.489670] LNet: Accept secure, port 988 [ 4455.064088] Key type lgssc registered [ 4455.416379] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4458.464759] Lustre: DEBUG MARKER: Iteration 28 [ 4458.550871] LustreError: 325396:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4458.550903] LustreError: 325397:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4458.557054] LustreError: 325396:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 4459.593521] Lustre: Mounted lustre-client [ 4460.018763] Lustre: Unmounted lustre-client [ 4460.823101] Key type lgssc unregistered [ 4460.912431] LNet: 325750:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4461.920825] LNet: Removed LNI 192.168.202.29@tcp [ 4462.115912] Key type .llcrypt unregistered [ 4462.117169] Key type ._llcrypt unregistered [ 4462.371921] alg: No test for adler32 (adler32-zlib) [ 4463.123286] Key type ._llcrypt registered [ 4463.124244] Key type .llcrypt registered [ 4463.183931] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4463.278617] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4463.353564] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4463.355344] LNet: Accept secure, port 988 [ 4464.928115] Key type lgssc registered [ 4465.253155] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4468.411256] Lustre: DEBUG MARKER: Iteration 29 [ 4468.499725] LustreError: 326535:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4468.499966] LustreError: 326536:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4468.506622] LustreError: 326535:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4469.545439] Lustre: Mounted lustre-client [ 4469.998494] Lustre: Unmounted lustre-client [ 4470.835512] Key type lgssc unregistered [ 4470.927367] LNet: 326884:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4471.968686] LNet: Removed LNI 192.168.202.29@tcp [ 4472.183083] Key type .llcrypt unregistered [ 4472.184154] Key type ._llcrypt unregistered [ 4472.495105] alg: No test for adler32 (adler32-zlib) [ 4473.249321] Key type ._llcrypt registered [ 4473.250363] Key type .llcrypt registered [ 4473.316399] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4473.401547] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4473.468801] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4473.470316] LNet: Accept secure, port 988 [ 4475.048111] Key type lgssc registered [ 4475.387154] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4478.430135] Lustre: DEBUG MARKER: Iteration 30 [ 4478.519983] LustreError: 327667:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4478.524136] LustreError: 327668:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4478.526519] LustreError: 327667:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4479.571915] Lustre: Mounted lustre-client [ 4479.573470] Lustre: Skipped 1 previous similar message [ 4480.034451] Lustre: Unmounted lustre-client [ 4480.873892] Key type lgssc unregistered [ 4480.964465] LNet: 328021:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4482.016418] LNet: Removed LNI 192.168.202.29@tcp [ 4482.234543] Key type .llcrypt unregistered [ 4482.235542] Key type ._llcrypt unregistered [ 4482.510614] alg: No test for adler32 (adler32-zlib) [ 4483.261265] Key type ._llcrypt registered [ 4483.262127] Key type .llcrypt registered [ 4483.326038] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4483.416559] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4483.486178] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4483.487813] LNet: Accept secure, port 988 [ 4485.064120] Key type lgssc registered [ 4485.397938] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4488.498042] Lustre: DEBUG MARKER: Iteration 31 [ 4488.584996] LustreError: 328805:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4488.585058] LustreError: 328806:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4488.591494] LustreError: 328805:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4489.631932] Lustre: Mounted lustre-client [ 4489.633131] Lustre: Skipped 1 previous similar message [ 4490.076614] Lustre: Unmounted lustre-client [ 4490.969583] Key type lgssc unregistered [ 4491.060352] LNet: 329155:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4492.064695] LNet: Removed LNI 192.168.202.29@tcp [ 4492.282360] Key type .llcrypt unregistered [ 4492.283270] Key type ._llcrypt unregistered [ 4492.530717] alg: No test for adler32 (adler32-zlib) [ 4493.282331] Key type ._llcrypt registered [ 4493.283262] Key type .llcrypt registered [ 4493.338617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4493.430918] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4493.494429] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4493.495820] LNet: Accept secure, port 988 [ 4495.072064] Key type lgssc registered [ 4495.446125] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4498.927630] Lustre: DEBUG MARKER: Iteration 32 [ 4499.017380] LustreError: 329939:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4499.017406] LustreError: 329940:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4499.023478] LustreError: 329939:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4500.068654] Lustre: Mounted lustre-client [ 4500.070026] Lustre: Skipped 1 previous similar message [ 4500.513913] Lustre: Unmounted lustre-client [ 4501.336159] Key type lgssc unregistered [ 4501.428465] LNet: 330292:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4502.432713] LNet: Removed LNI 192.168.202.29@tcp [ 4502.642568] Key type .llcrypt unregistered [ 4502.643837] Key type ._llcrypt unregistered [ 4502.911832] alg: No test for adler32 (adler32-zlib) [ 4503.663367] Key type ._llcrypt registered [ 4503.664531] Key type .llcrypt registered [ 4503.730079] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4503.820634] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4503.889885] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4503.891554] LNet: Accept secure, port 988 [ 4505.472091] Key type lgssc registered [ 4505.789277] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4508.880639] Lustre: DEBUG MARKER: Iteration 33 [ 4508.975434] LustreError: 331075:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4508.975905] LustreError: 331076:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4508.980028] LustreError: 331075:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4510.025636] Lustre: Mounted lustre-client [ 4510.027028] Lustre: Skipped 1 previous similar message [ 4510.507570] Lustre: Unmounted lustre-client [ 4510.508846] Lustre: Skipped 1 previous similar message [ 4511.301648] Key type lgssc unregistered [ 4511.394566] LNet: 331428:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4512.416803] LNet: Removed LNI 192.168.202.29@tcp [ 4512.636422] Key type .llcrypt unregistered [ 4512.637476] Key type ._llcrypt unregistered [ 4512.900983] alg: No test for adler32 (adler32-zlib) [ 4513.652364] Key type ._llcrypt registered [ 4513.653971] Key type .llcrypt registered [ 4513.728176] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4513.828162] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4513.907528] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4513.909752] LNet: Accept secure, port 988 [ 4515.496096] Key type lgssc registered [ 4515.839789] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4519.014518] Lustre: DEBUG MARKER: Iteration 34 [ 4519.102281] LustreError: 332213:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4519.102650] LustreError: 332212:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4519.107368] LustreError: 332213:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4520.143214] Lustre: Mounted lustre-client [ 4520.144752] Lustre: Skipped 1 previous similar message [ 4520.619329] Lustre: Unmounted lustre-client [ 4520.620750] Lustre: Skipped 1 previous similar message [ 4521.488408] Key type lgssc unregistered [ 4521.590478] LNet: 332562:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4522.656730] LNet: Removed LNI 192.168.202.29@tcp [ 4522.862831] Key type .llcrypt unregistered [ 4522.864602] Key type ._llcrypt unregistered [ 4523.118555] alg: No test for adler32 (adler32-zlib) [ 4523.871317] Key type ._llcrypt registered [ 4523.872354] Key type .llcrypt registered [ 4523.935123] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4524.028359] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4524.096798] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4524.098208] LNet: Accept secure, port 988 [ 4525.680052] Key type lgssc registered [ 4525.990409] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4529.070972] Lustre: DEBUG MARKER: Iteration 35 [ 4529.153368] LustreError: 333345:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4529.153398] LustreError: 333346:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4529.158024] LustreError: 333345:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4530.202116] Lustre: Mounted lustre-client [ 4530.203689] Lustre: Skipped 1 previous similar message [ 4530.666220] Lustre: Unmounted lustre-client [ 4530.667934] Lustre: Skipped 1 previous similar message [ 4531.525401] Key type lgssc unregistered [ 4531.630410] LNet: 333699:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4532.640502] LNet: Removed LNI 192.168.202.29@tcp [ 4532.842906] Key type .llcrypt unregistered [ 4532.843894] Key type ._llcrypt unregistered [ 4533.149135] alg: No test for adler32 (adler32-zlib) [ 4533.900318] Key type ._llcrypt registered [ 4533.901308] Key type .llcrypt registered [ 4533.958044] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4534.047924] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4534.119682] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4534.121056] LNet: Accept secure, port 988 [ 4535.696110] Key type lgssc registered [ 4536.024600] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4539.076373] Lustre: DEBUG MARKER: Iteration 36 [ 4539.166945] LustreError: 334482:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4539.167021] LustreError: 334483:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4539.172735] LustreError: 334482:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4540.210871] Lustre: Mounted lustre-client [ 4540.212578] Lustre: Skipped 1 previous similar message [ 4540.680687] Lustre: Unmounted lustre-client [ 4540.681748] Lustre: Skipped 1 previous similar message [ 4541.541101] Key type lgssc unregistered [ 4541.633506] LNet: 334831:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4542.688735] LNet: Removed LNI 192.168.202.29@tcp [ 4542.908898] Key type .llcrypt unregistered [ 4542.910498] Key type ._llcrypt unregistered [ 4543.157488] alg: No test for adler32 (adler32-zlib) [ 4543.908285] Key type ._llcrypt registered [ 4543.909702] Key type .llcrypt registered [ 4543.976100] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4544.074719] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4544.147788] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4544.149139] LNet: Accept secure, port 988 [ 4545.728099] Key type lgssc registered [ 4546.094554] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4549.376218] Lustre: DEBUG MARKER: Iteration 37 [ 4549.463677] LustreError: 335614:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4549.467074] LustreError: 335616:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4549.469573] LustreError: 335614:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4550.509389] Lustre: Mounted lustre-client [ 4550.510555] Lustre: Skipped 1 previous similar message [ 4550.935885] Lustre: Unmounted lustre-client [ 4551.801256] Key type lgssc unregistered [ 4551.890446] LNet: 335969:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4552.928749] LNet: Removed LNI 192.168.202.29@tcp [ 4553.142131] Key type .llcrypt unregistered [ 4553.143165] Key type ._llcrypt unregistered [ 4553.418583] alg: No test for adler32 (adler32-zlib) [ 4554.170300] Key type ._llcrypt registered [ 4554.171309] Key type .llcrypt registered [ 4554.240959] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4554.337240] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4554.414247] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4554.415882] LNet: Accept secure, port 988 [ 4555.992114] Key type lgssc registered [ 4556.335810] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4559.573433] Lustre: DEBUG MARKER: Iteration 38 [ 4559.664519] LustreError: 336752:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4559.664559] LustreError: 336753:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4559.671159] LustreError: 336752:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 4560.711562] Lustre: Mounted lustre-client [ 4560.713042] Lustre: Skipped 1 previous similar message [ 4561.173464] Lustre: Unmounted lustre-client [ 4561.174747] Lustre: Skipped 1 previous similar message [ 4562.098039] Key type lgssc unregistered [ 4562.206446] LNet: 337102:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4563.232721] LNet: Removed LNI 192.168.202.29@tcp [ 4563.453701] Key type .llcrypt unregistered [ 4563.454719] Key type ._llcrypt unregistered [ 4563.737600] alg: No test for adler32 (adler32-zlib) [ 4564.489274] Key type ._llcrypt registered [ 4564.490284] Key type .llcrypt registered [ 4564.558904] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4564.656907] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4564.733342] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4564.734716] LNet: Accept secure, port 988 [ 4566.312101] Key type lgssc registered [ 4566.665262] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4569.799333] Lustre: DEBUG MARKER: Iteration 39 [ 4569.890845] LustreError: 337885:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4569.891260] LustreError: 337886:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4569.895842] LustreError: 337885:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4570.938676] Lustre: Mounted lustre-client [ 4570.940071] Lustre: Skipped 1 previous similar message [ 4571.437532] Lustre: Unmounted lustre-client [ 4571.438852] Lustre: Skipped 1 previous similar message [ 4572.399095] Key type lgssc unregistered [ 4572.502514] LNet: 338235:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4573.537308] LNet: Removed LNI 192.168.202.29@tcp [ 4573.779184] Key type .llcrypt unregistered [ 4573.780663] Key type ._llcrypt unregistered [ 4574.080248] alg: No test for adler32 (adler32-zlib) [ 4574.831337] Key type ._llcrypt registered [ 4574.832491] Key type .llcrypt registered [ 4574.908824] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4575.018164] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4575.097386] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4575.100387] LNet: Accept secure, port 988 [ 4576.688099] Key type lgssc registered [ 4577.051703] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4580.272762] Lustre: DEBUG MARKER: Iteration 40 [ 4580.364862] LustreError: 339018:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4580.364915] LustreError: 339019:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4580.369949] LustreError: 339018:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4581.418934] Lustre: Mounted lustre-client [ 4581.419964] Lustre: Skipped 1 previous similar message [ 4581.865254] Lustre: Unmounted lustre-client [ 4582.730375] Key type lgssc unregistered [ 4582.824278] LNet: 339366:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4583.840951] LNet: Removed LNI 192.168.202.29@tcp [ 4584.063989] Key type .llcrypt unregistered [ 4584.065450] Key type ._llcrypt unregistered [ 4584.341136] alg: No test for adler32 (adler32-zlib) [ 4585.093341] Key type ._llcrypt registered [ 4585.094564] Key type .llcrypt registered [ 4585.161451] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4585.260396] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4585.329265] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4585.331385] LNet: Accept secure, port 988 [ 4586.904134] Key type lgssc registered [ 4587.240312] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4590.333200] Lustre: DEBUG MARKER: Iteration 41 [ 4590.423062] LustreError: 340149:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4590.423123] LustreError: 340150:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4590.427657] LustreError: 340149:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4591.474228] Lustre: Mounted lustre-client [ 4591.475208] Lustre: Skipped 1 previous similar message [ 4591.891141] Lustre: Unmounted lustre-client [ 4592.697808] Key type lgssc unregistered [ 4592.778432] LNet: 340504:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4593.824659] LNet: Removed LNI 192.168.202.29@tcp [ 4594.035422] Key type .llcrypt unregistered [ 4594.036274] Key type ._llcrypt unregistered [ 4594.269770] alg: No test for adler32 (adler32-zlib) [ 4595.023376] Key type ._llcrypt registered [ 4595.024383] Key type .llcrypt registered [ 4595.084218] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4595.181123] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4595.249026] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4595.251262] LNet: Accept secure, port 988 [ 4596.824106] Key type lgssc registered [ 4597.163631] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4600.445985] Lustre: DEBUG MARKER: Iteration 42 [ 4600.543703] LustreError: 341288:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4600.543947] LustreError: 341289:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4600.552661] LustreError: 341288:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 4601.603064] Lustre: Mounted lustre-client [ 4601.604505] Lustre: Skipped 1 previous similar message [ 4602.052828] Lustre: Unmounted lustre-client [ 4602.054026] Lustre: Skipped 1 previous similar message [ 4602.934369] Key type lgssc unregistered [ 4603.028453] LNet: 341640:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4604.064616] LNet: Removed LNI 192.168.202.29@tcp [ 4604.267204] Key type .llcrypt unregistered [ 4604.268321] Key type ._llcrypt unregistered [ 4604.566802] alg: No test for adler32 (adler32-zlib) [ 4605.318237] Key type ._llcrypt registered [ 4605.319363] Key type .llcrypt registered [ 4605.381965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4605.478451] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4605.550434] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4605.552111] LNet: Accept secure, port 988 [ 4607.128102] Key type lgssc registered [ 4607.457917] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4610.588545] Lustre: DEBUG MARKER: Iteration 43 [ 4610.676347] LustreError: 342423:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4610.676593] LustreError: 342424:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4610.680341] LustreError: 342423:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4611.721254] Lustre: Mounted lustre-client [ 4611.722284] Lustre: Skipped 1 previous similar message [ 4612.211124] Lustre: Unmounted lustre-client [ 4613.050776] Key type lgssc unregistered [ 4613.145312] LNet: 342780:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4614.176718] LNet: Removed LNI 192.168.202.29@tcp [ 4614.401619] Key type .llcrypt unregistered [ 4614.403277] Key type ._llcrypt unregistered [ 4614.742453] alg: No test for adler32 (adler32-zlib) [ 4615.496329] Key type ._llcrypt registered [ 4615.497253] Key type .llcrypt registered [ 4615.560858] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4615.658707] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4615.725024] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4615.727052] LNet: Accept secure, port 988 [ 4617.304086] Key type lgssc registered [ 4617.634686] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4620.802109] Lustre: DEBUG MARKER: Iteration 44 [ 4620.901680] LustreError: 343564:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4620.902917] LustreError: 343565:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4620.909143] LustreError: 343564:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 4621.959142] Lustre: Mounted lustre-client [ 4621.960228] Lustre: Skipped 1 previous similar message [ 4622.411184] Lustre: Unmounted lustre-client [ 4623.302764] Key type lgssc unregistered [ 4623.404693] LNet: 343917:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4624.416721] LNet: Removed LNI 192.168.202.29@tcp [ 4624.648361] Key type .llcrypt unregistered [ 4624.649247] Key type ._llcrypt unregistered [ 4624.895261] alg: No test for adler32 (adler32-zlib) [ 4625.646358] Key type ._llcrypt registered [ 4625.648030] Key type .llcrypt registered [ 4625.709091] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4625.794778] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4625.866200] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4625.867450] LNet: Accept secure, port 988 [ 4627.440117] Key type lgssc registered [ 4627.778201] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4630.925445] Lustre: DEBUG MARKER: Iteration 45 [ 4631.008959] LustreError: 344701:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4631.008983] LustreError: 344702:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4631.016417] LustreError: 344701:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 4632.059985] Lustre: Mounted lustre-client [ 4632.527927] Lustre: Unmounted lustre-client [ 4632.528856] Lustre: Skipped 1 previous similar message [ 4633.408274] Key type lgssc unregistered [ 4633.503406] LNet: 345049:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4634.528653] LNet: Removed LNI 192.168.202.29@tcp [ 4634.743375] Key type .llcrypt unregistered [ 4634.744911] Key type ._llcrypt unregistered [ 4634.973876] alg: No test for adler32 (adler32-zlib) [ 4635.725349] Key type ._llcrypt registered [ 4635.726271] Key type .llcrypt registered [ 4635.793017] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4635.878801] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4635.952459] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4635.954083] LNet: Accept secure, port 988 [ 4637.536104] Key type lgssc registered [ 4637.856271] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4641.136447] Lustre: DEBUG MARKER: Iteration 46 [ 4641.227644] LustreError: 345833:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4641.227683] LustreError: 345834:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4641.234262] LustreError: 345833:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 4642.277037] Lustre: Mounted lustre-client [ 4642.278226] Lustre: Skipped 1 previous similar message [ 4642.736276] Lustre: Unmounted lustre-client [ 4643.584731] Key type lgssc unregistered [ 4643.683374] LNet: 346187:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4644.704475] LNet: Removed LNI 192.168.202.29@tcp [ 4644.910414] Key type .llcrypt unregistered [ 4644.911434] Key type ._llcrypt unregistered [ 4645.165407] alg: No test for adler32 (adler32-zlib) [ 4645.916239] Key type ._llcrypt registered [ 4645.917024] Key type .llcrypt registered [ 4645.983213] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4646.076507] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4646.146324] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4646.147776] LNet: Accept secure, port 988 [ 4647.720108] Key type lgssc registered [ 4648.023579] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4651.122810] Lustre: DEBUG MARKER: Iteration 47 [ 4651.235347] LustreError: 346970:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4651.235399] LustreError: 346971:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4651.241675] LustreError: 346970:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4652.291558] Lustre: Mounted lustre-client [ 4652.751051] Lustre: Unmounted lustre-client [ 4653.604511] Key type lgssc unregistered [ 4653.700447] LNet: 347324:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4654.752488] LNet: Removed LNI 192.168.202.29@tcp [ 4654.976379] Key type .llcrypt unregistered [ 4654.977906] Key type ._llcrypt unregistered [ 4655.236836] alg: No test for adler32 (adler32-zlib) [ 4655.989264] Key type ._llcrypt registered [ 4655.990085] Key type .llcrypt registered [ 4656.051626] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4656.140734] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4656.208668] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4656.210589] LNet: Accept secure, port 988 [ 4657.784113] Key type lgssc registered [ 4658.116902] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4661.253446] Lustre: DEBUG MARKER: Iteration 48 [ 4661.346767] LustreError: 348109:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4661.348597] LustreError: 348110:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4661.353469] LustreError: 348109:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4662.401409] Lustre: Mounted lustre-client [ 4662.403384] Lustre: Skipped 1 previous similar message [ 4662.907089] Lustre: Unmounted lustre-client [ 4662.908893] Lustre: Skipped 1 previous similar message [ 4663.808486] Key type lgssc unregistered [ 4663.911529] LNet: 348457:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4664.928725] LNet: Removed LNI 192.168.202.29@tcp [ 4665.134833] Key type .llcrypt unregistered [ 4665.135968] Key type ._llcrypt unregistered [ 4665.397930] alg: No test for adler32 (adler32-zlib) [ 4666.150239] Key type ._llcrypt registered [ 4666.151177] Key type .llcrypt registered [ 4666.216657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4666.316973] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4666.387114] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4666.388694] LNet: Accept secure, port 988 [ 4667.968076] Key type lgssc registered [ 4668.325980] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4671.659737] Lustre: DEBUG MARKER: Iteration 49 [ 4671.754042] LustreError: 349240:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4671.754172] LustreError: 349241:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4671.758563] LustreError: 349240:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4672.802910] Lustre: Mounted lustre-client [ 4672.803954] Lustre: Skipped 1 previous similar message [ 4673.278432] Lustre: Unmounted lustre-client [ 4674.166050] Key type lgssc unregistered [ 4674.264348] LNet: 349589:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4675.296448] LNet: Removed LNI 192.168.202.29@tcp [ 4675.500914] Key type .llcrypt unregistered [ 4675.502077] Key type ._llcrypt unregistered [ 4675.763822] alg: No test for adler32 (adler32-zlib) [ 4676.516415] Key type ._llcrypt registered [ 4676.517726] Key type .llcrypt registered [ 4676.584869] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4676.679600] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4676.754118] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4676.755479] LNet: Accept secure, port 988 [ 4678.336057] Key type lgssc registered [ 4678.668249] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4681.782415] Lustre: DEBUG MARKER: Iteration 50 [ 4681.872495] LustreError: 350372:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4681.872686] LustreError: 350373:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4681.877985] LustreError: 350372:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4682.917637] Lustre: Mounted lustre-client [ 4683.510833] Lustre: Unmounted lustre-client [ 4684.487836] Key type lgssc unregistered [ 4684.581503] LNet: 350726:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4685.600746] LNet: Removed LNI 192.168.202.29@tcp [ 4685.813719] Key type .llcrypt unregistered [ 4685.815094] Key type ._llcrypt unregistered [ 4686.105822] alg: No test for adler32 (adler32-zlib) [ 4686.859377] Key type ._llcrypt registered [ 4686.860531] Key type .llcrypt registered [ 4686.937794] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4687.046807] Lustre: Lustre: Build Version: 2.15.8_1_gea526c4 [ 4687.127513] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4687.129351] LNet: Accept secure, port 988 [ 4688.720145] Key type lgssc registered [ 4689.056995] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4692.889263] Lustre: Mounted lustre-client [ 4695.028280] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 15:09:42 (1772309382) [ 4701.664141] Lustre: 352018:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772309382/real 1772309382] req@0000000068f41c55 x1858401074155904/t0(0) o36->lustre-MDT0000-mdc-ffff8d64073a2000@192.168.202.129@tcp:12/10 lens 496/440 e 0 to 1 dl 1772309389 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'ln.0' [ 4701.672858] Lustre: lustre-MDT0000-mdc-ffff8d64073a2000: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4701.680293] Lustre: lustre-MDT0000-mdc-ffff8d64073a2000: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 4708.832099] Lustre: 352018:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772309389/real 1772309389] req@0000000068f41c55 x1858401074155904/t0(0) o36->lustre-MDT0000-mdc-ffff8d64073a2000@192.168.202.129@tcp:12/10 lens 496/440 e 0 to 1 dl 1772309396 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 4708.838486] Lustre: lustre-MDT0000-mdc-ffff8d64073a2000: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4708.846772] Lustre: lustre-MDT0000-mdc-ffff8d64073a2000: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 4716.000091] Lustre: 352018:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772309396/real 1772309396] req@0000000068f41c55 x1858401074155904/t0(0) o36->lustre-MDT0000-mdc-ffff8d64073a2000@192.168.202.129@tcp:12/10 lens 496/440 e 0 to 1 dl 1772309403 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 4716.006702] Lustre: lustre-MDT0000-mdc-ffff8d64073a2000: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4716.014511] Lustre: lustre-MDT0000-mdc-ffff8d64073a2000: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 4723.168138] Lustre: 352018:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772309403/real 1772309403] req@0000000068f41c55 x1858401074155904/t0(0) o36->lustre-MDT0000-mdc-ffff8d64073a2000@192.168.202.129@tcp:12/10 lens 496/440 e 0 to 1 dl 1772309410 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 4723.176889] Lustre: lustre-MDT0000-mdc-ffff8d64073a2000: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4723.187404] Lustre: lustre-MDT0000-mdc-ffff8d64073a2000: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 4730.336089] Lustre: 352018:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772309410/real 1772309410] req@0000000068f41c55 x1858401074155904/t0(0) o36->lustre-MDT0000-mdc-ffff8d64073a2000@192.168.202.129@tcp:12/10 lens 496/440 e 0 to 1 dl 1772309417 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 4730.342195] Lustre: lustre-MDT0000-mdc-ffff8d64073a2000: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4730.352285] Lustre: lustre-MDT0000-mdc-ffff8d64073a2000: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 4730.831122] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 15:10:17 (1772309417) [ 4731.282571] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 4731.797931] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 15:10:18 (1772309418) [ 4732.253870] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 4732.740489] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 15:10:19 (1772309419) [ 4734.706081] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 4735.199691] Lustre: DEBUG MARKER: == sanityn test complete, duration 4627 sec ============== 15:10:22 (1772309422) [ 4768.068556] Lustre: Unmounted lustre-client [ 4779.654701] Key type lgssc unregistered [ 4779.750398] LNet: 354413:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4780.768855] LNet: Removed LNI 192.168.202.29@tcp [ 4780.981992] Key type .llcrypt unregistered [ 4780.983575] Key type ._llcrypt unregistered