[ 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-10.fc44 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 462795015 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 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 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.001010] APIC: Switch to symmetric I/O mode setup [ 0.002259] x2apic enabled [ 0.003004] Switched APIC routing to physical x2apic. [ 0.004006] kvm-guest: setup PV IPIs [ 0.006442] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007008] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008005] pid_max: default: 32768 minimum: 301 [ 0.009076] LSM: Security Framework initializing [ 0.010027] Yama: becoming mindful. [ 0.011020] SELinux: Initializing. [ 0.012040] *** VALIDATE selinux *** [ 0.019382] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023413] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024077] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.025059] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026053] *** VALIDATE tmpfs *** [ 0.027262] *** VALIDATE proc *** [ 0.028193] *** VALIDATE cgroup *** [ 0.029004] *** VALIDATE cgroup2 *** [ 0.030173] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.031120] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.032003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.033019] Spectre V2 : User space: Vulnerable [ 0.034003] Speculative Store Bypass: Vulnerable [ 0.037054] debug: unmapping init [mem 0xffffffffa6259000-0xffffffffa6260fff] [ 0.039789] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.040480] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.041010] ... version: 2 [ 0.042005] ... bit width: 48 [ 0.043005] ... generic registers: 4 [ 0.044005] ... value mask: 0000ffffffffffff [ 0.045005] ... max period: 00007fffffffffff [ 0.046009] ... fixed-purpose events: 3 [ 0.047009] ... event mask: 000000070000000f [ 0.048185] rcu: Hierarchical SRCU implementation. [ 0.050172] smp: Bringing up secondary CPUs ... [ 0.051371] x86: Booting SMP configuration: [ 0.052014] .... node #0, CPUs: #1 #2 #3 [ 0.054664] smp: Brought up 1 node, 4 CPUs [ 0.056006] smpboot: Max logical packages: 1 [ 0.057008] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.235313] node 0 deferred pages initialised in 177ms [ 0.238008] devtmpfs: initialized [ 0.239207] x86/mm: Memory block size: 128MB [ 0.241337] gcov: version magic: 0x41383552 [ 0.243265] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.244056] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.245238] pinctrl core: initialized pinctrl subsystem [ 0.246100] [ 0.246481] ************************************************************* [ 0.248008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.250006] ** ** [ 0.251005] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.252007] ** ** [ 0.254006] ** This means that this kernel is built to expose internal ** [ 0.255005] ** IOMMU data structures, which may compromise security on ** [ 0.257006] ** your system. ** [ 0.258005] ** ** [ 0.259006] ** If you see this message and you are not debugging the ** [ 0.261006] ** kernel, report this immediately to your vendor! ** [ 0.262005] ** ** [ 0.264006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.265005] ************************************************************* [ 0.266424] NET: Registered protocol family 16 [ 0.268291] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.269027] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.271025] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.273078] cpuidle: using governor menu [ 0.274296] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.275420] PCI: Using configuration type 1 for base access [ 0.277089] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.283069] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.284010] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.286086] cryptd: max_cpu_qlen set to 1000 [ 0.287151] ACPI: Added _OSI(Module Device) [ 0.288008] ACPI: Added _OSI(Processor Device) [ 0.288881] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.290009] ACPI: Added _OSI(Processor Aggregator Device) [ 0.293127] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.297565] ACPI: Interpreter enabled [ 0.298034] ACPI: PM: (supports S0 S3 S4 S5) [ 0.298904] ACPI: Using IOAPIC for interrupt routing [ 0.300038] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.302212] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.308000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.310018] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.312011] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.315032] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.318865] acpiphp: Slot [2] registered [ 0.319052] acpiphp: Slot [5] registered [ 0.319833] acpiphp: Slot [6] registered [ 0.321048] acpiphp: Slot [3] registered [ 0.321896] acpiphp: Slot [4] registered [ 0.322049] acpiphp: Slot [7] registered [ 0.322938] acpiphp: Slot [8] registered [ 0.324042] acpiphp: Slot [9] registered [ 0.324834] acpiphp: Slot [10] registered [ 0.326042] acpiphp: Slot [11] registered [ 0.326993] acpiphp: Slot [12] registered [ 0.328042] acpiphp: Slot [13] registered [ 0.328884] acpiphp: Slot [14] registered [ 0.329047] acpiphp: Slot [15] registered [ 0.329936] acpiphp: Slot [16] registered [ 0.331041] acpiphp: Slot [17] registered [ 0.331905] acpiphp: Slot [18] registered [ 0.333044] acpiphp: Slot [19] registered [ 0.333871] acpiphp: Slot [20] registered [ 0.334054] acpiphp: Slot [21] registered [ 0.334896] acpiphp: Slot [22] registered [ 0.336044] acpiphp: Slot [23] registered [ 0.336911] acpiphp: Slot [24] registered [ 0.338042] acpiphp: Slot [25] registered [ 0.338885] acpiphp: Slot [26] registered [ 0.340062] acpiphp: Slot [27] registered [ 0.341056] acpiphp: Slot [28] registered [ 0.341961] acpiphp: Slot [29] registered [ 0.343043] acpiphp: Slot [30] registered [ 0.343930] acpiphp: Slot [31] registered [ 0.345035] PCI host bridge to bus 0000:00 [ 0.345910] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.348023] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.349012] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.351009] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.352008] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.354010] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.355130] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.357675] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.358776] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.364480] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.366385] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.368008] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.370009] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.372007] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.373318] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.374549] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.376019] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.378456] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.380915] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.386683] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.389007] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.393064] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.396007] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.398600] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.407703] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.413184] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.416566] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.418623] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.427009] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.433276] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.434217] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.436217] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.438222] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.439114] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.443097] iommu: Default domain type: Passthrough [ 0.444274] SCSI subsystem initialized [ 0.445069] ACPI: bus type USB registered [ 0.446072] usbcore: registered new interface driver usbfs [ 0.448035] usbcore: registered new interface driver hub [ 0.449028] usbcore: registered new device driver usb [ 0.450037] pps_core: LinuxPPS API ver. 1 registered [ 0.451005] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.453024] PTP clock support registered [ 0.455049] EDAC MC: Ver: 3.0.0 [ 0.456089] PCI: Using ACPI for IRQ routing [ 0.457636] NetLabel: Initializing [ 0.458006] NetLabel: domain hash size = 128 [ 0.458960] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.461054] NetLabel: unlabeled traffic allowed by default [ 0.463024] vgaarb: loaded [ 0.463673] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.465009] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.472267] clocksource: Switched to clocksource kvm-clock [ 0.552845] VFS: Disk quotas dquot_6.6.0 [ 0.553882] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.555518] *** VALIDATE ramfs *** [ 0.556379] *** VALIDATE hugetlbfs *** [ 0.557327] pnp: PnP ACPI init [ 0.558939] pnp: PnP ACPI: found 6 devices [ 0.572452] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.575276] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.577174] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.579151] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.581215] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.582749] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.584842] NET: Registered protocol family 2 [ 0.586828] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.591479] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.593776] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.597520] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.599461] TCP: Hash tables configured (established 65536 bind 65536) [ 0.601165] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.602895] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.604515] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.606218] NET: Registered protocol family 1 [ 0.607791] RPC: Registered named UNIX socket transport module. [ 0.609142] RPC: Registered udp transport module. [ 0.610053] RPC: Registered tcp transport module. [ 0.610992] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.612207] NET: Registered protocol family 44 [ 0.613151] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.614331] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.615454] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.616765] PCI: CLS 0 bytes, default 64 [ 0.617653] Unpacking initramfs... [ 1.762235] debug: unmapping init [mem 0xffff95bc3cc64000-0xffff95bc3ffcffff] [ 1.766732] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.768078] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 1.769799] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.217965] Initialise system trusted keyrings [ 2.219204] Key type blacklist registered [ 2.220634] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.227238] zbud: loaded [ 2.229394] *** VALIDATE nfs *** [ 2.230862] *** VALIDATE nfs4 *** [ 2.232027] pstore: using deflate compression [ 2.233910] Platform Keyring initialized [ 2.306516] NET: Registered protocol family 38 [ 2.307743] Key type asymmetric registered [ 2.308819] Asymmetric key parser 'x509' registered [ 2.310111] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.311962] io scheduler mq-deadline registered [ 2.313079] io scheduler kyber registered [ 2.314198] io scheduler bfq registered [ 2.315416] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.317412] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.319399] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.321100] ACPI: Power Button [PWRF] [ 2.324803] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.329381] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.336441] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.361981] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.389141] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.392728] Non-volatile memory driver v1.3 [ 2.393687] Linux agpgart interface v0.103 [ 2.414299] virtio_blk virtio1: [vda] 145160 512-byte logical blocks (74.3 MB/70.9 MiB) [ 2.416044] vda: detected capacity change from 0 to 74321920 [ 2.425575] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.427478] vdb: detected capacity change from 0 to 1073741824 [ 2.431168] libphy: Fixed MDIO Bus: probed [ 2.434029] usbcore: registered new interface driver usbserial_generic [ 2.436044] usbserial: USB Serial support registered for generic [ 2.437515] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.440099] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.441114] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.442484] mousedev: PS/2 mouse device common for all mice [ 2.444237] rtc_cmos 00:05: RTC can wake from S4 [ 2.445667] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.447887] rtc_cmos 00:05: registered as rtc0 [ 2.449296] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.450173] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.452655] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.454704] intel_pstate: CPU model not supported [ 2.459250] hid: raw HID events driver (C) Jiri Kosina [ 2.460445] usbcore: registered new interface driver usbhid [ 2.461650] usbhid: USB HID core driver [ 2.462701] drop_monitor: Initializing network drop monitor service [ 2.464319] Initializing XFRM netlink socket [ 2.466112] NET: Registered protocol family 10 [ 2.468731] Segment Routing with IPv6 [ 2.470136] NET: Registered protocol family 17 [ 2.471868] mpls_gso: MPLS GSO support [ 2.477847] RAS: Correctable Errors collector initialized. [ 2.479906] AVX version of gcm_enc/dec engaged. [ 2.481519] AES CTR mode by8 optimization enabled [ 2.537805] sched_clock: Marking stable (2537784248, 0)->(3191399641, -653615393) [ 2.540913] registered taskstats version 1 [ 2.542973] Loading compiled-in X.509 certificates [ 2.544806] zswap: loaded using pool lzo/zbud [ 2.561948] Key type big_key registered [ 2.572497] Key type encrypted registered [ 2.573774] ima: No TPM chip found, activating TPM-bypass! [ 2.575139] ima: Allocated hash algorithm: sha1 [ 2.576292] ima: No architecture policies found [ 2.577502] evm: Initialising EVM extended attributes: [ 2.578750] evm: security.selinux [ 2.579517] evm: security.ima [ 2.580313] evm: security.capability [ 2.581299] evm: HMAC attrs: 0x1 [ 2.582953] rtc_cmos 00:05: setting system clock to 2026-07-01 00:29:05 UTC (1782865745) [ 2.587464] debug: unmapping init [mem 0xffffffffa7203000-0xffffffffa73fffff] [ 2.589804] debug: unmapping init [mem 0xffffffffa5f82000-0xffffffffa6258fff] [ 2.597052] Write protecting the kernel read-only data: 28672k [ 2.599179] debug: unmapping init [mem 0xffffffffa4603000-0xffffffffa47fffff] [ 2.600979] debug: unmapping init [mem 0xffffffffa4f14000-0xffffffffa4ffffff] [ 2.624512] 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) [ 2.630282] systemd[1]: Detected virtualization kvm. [ 2.631694] systemd[1]: Detected architecture x86-64. [ 2.632979] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 2.657612] systemd[1]: No hostname configured. [ 2.658848] systemd[1]: Set hostname to . [ 2.660387] random: systemd: uninitialized urandom read (16 bytes read) [ 2.661954] systemd[1]: Initializing machine ID from random generator. [ 2.685478] random: ln: uninitialized urandom read (6 bytes read) [ 2.771722] random: systemd: uninitialized urandom read (16 bytes read) [ 2.773424] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 2.776649] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 2.779655] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local File Systems. [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Paths. [ OK ] Reached target Timers. Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.262049] device-mapper: uevent: version 1.0.3 [ 3.264572] 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. [ 3.967199] virtio_net virtio0 ens2: renamed from eth0 Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy[ 3.997179] random: fast init done Gatherer Daemon. [ 4.019915] scsi host0: ata_piix [ 4.038577] scsi host1: ata_piix [ 4.040306] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.043103] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 7.698777] dracut-initqueue[579]: RTNETLINK answers: File exists [ 9.090702] random: crng init done [ 9.092110] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 9.551496] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 10.938522] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.218143] SELinux: Disabled at runtime. [ 11.278033] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.285328] systemd[1]: Detected virtualization kvm. [ 11.286921] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.783208] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.788176] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.793207] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.798219] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.800653] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.807468] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.811814] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice User and Session Slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ 11.973301] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS 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. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Reached target Slices. [ OK ] Reached target Paths. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Apply Kernel Variables... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting POSIX Message Queue File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started 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 /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 12.492598] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.836112] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.919218] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.133085] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.187895] EDAC sbridge: Ver: 1.1.2 [ 14.380742] Key type dns_resolver registered [ 14.672542] NFS: Registering the id_resolver key type [ 14.674603] Key type id_resolver registered [ 14.676528] 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 Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started dnf makecache --timer. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ 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 Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Authorization Manager. [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg301-client login: [ 39.165137] libcfs: loading out-of-tree module taints kernel. [ 39.246556] Key type ._llcrypt registered [ 39.248369] Key type .llcrypt registered [ 39.505312] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 39.512641] alg: No test for adler32 (adler32-zlib) [ 40.496894] Lustre: Lustre: Build Version: 2.17.54_83_g326f97e [ 40.791852] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 42.399113] Key type lgssc registered [ 42.904593] Lustre: Echo OBD driver; http://www.lustre.org/ [ 101.120070] Lustre: Mounted lustre-client [ 103.397864] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 109.909040] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing check_logdir /tmp/testlogs/ [ 111.306851] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing yml_node [ 112.649196] Lustre: DEBUG MARKER: Client: 2.17.54.83 [ 113.457356] Lustre: DEBUG MARKER: MDS: 2.17.54.83 [ 114.239895] Lustre: DEBUG MARKER: OSS: 2.17.54.83 [ 114.764820] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Tue Jun 30 20:30:57 EDT 2026 [ 120.108398] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 120.646171] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 121.204980] Lustre: DEBUG MARKER: === sanityn: start setup 20:31:03 (1782865863) === [ 121.415227] Lustre: Mounted lustre-client [ 123.289360] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing check_config_client /mnt/lustre [ 126.431160] Lustre: lustre-OST0000-osc-ffff95bc851fe000: disconnect after 24s idle [ 129.843984] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 134.043623] Lustre: DEBUG MARKER: === sanityn: finish setup 20:31:16 (1782865876) === [ 134.695454] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 20:31:17 (1782865877) [ 137.429254] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 20:31:19 (1782865879) [ 139.644412] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 20:31:22 (1782865882) [ 141.886728] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 20:31:24 (1782865884) [ 144.122499] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 20:31:26 (1782865886) [ 146.222128] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 20:31:28 (1782865888) [ 148.411382] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 20:31:30 (1782865890) [ 150.559681] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 20:31:32 (1782865892) [ 152.797076] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 20:31:35 (1782865895) [ 155.059144] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 20:31:37 (1782865897) [ 157.295109] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 20:31:39 (1782865899) [ 160.495856] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 20:31:42 (1782865902) [ 162.782171] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 20:31:45 (1782865905) [ 165.029681] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 20:31:47 (1782865907) [ 167.161567] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 20:31:49 (1782865909) [ 169.269943] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 20:31:51 (1782865911) [ 171.420046] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 20:31:53 (1782865913) [ 173.404813] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 20:31:55 (1782865915) [ 175.634443] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 20:31:58 (1782865918) [ 177.603147] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 20:32:00 (1782865920) [ 179.678271] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 20:32:02 (1782865922) [ 179.820637] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 319.547036] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 20:34:22 (1782866062) [ 321.864097] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 20:34:24 (1782866064) [ 324.101205] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 20:34:26 (1782866066) [ 326.349890] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 20:34:28 (1782866068) [ 328.910789] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 20:34:31 (1782866071) [ 331.345653] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 20:34:33 (1782866073) [ 331.889882] Lustre: DEBUG MARKER: chmod [ 333.777225] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 20:34:36 (1782866076) [ 871.087454] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 20:43:33 (1782866613) [ 912.980158] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 20:44:15 (1782866655) [ 933.147594] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 20:44:35 (1782866675) [ 967.499371] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 20:45:09 (1782866709) [ 977.726598] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 20:45:20 (1782866720) [ 980.124398] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 20:45:22 (1782866722) [ 980.671433] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.720852] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.744824] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.770399] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.796100] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.819097] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.844828] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.874555] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.899361] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.922998] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.947064] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.970876] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 980.994904] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.019773] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.042608] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.065255] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.087848] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.121483] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.145413] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.170082] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.195355] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.218223] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.240068] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.262886] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.285591] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.308346] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.330501] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.352164] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.373822] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.401709] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.427509] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.454820] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.480431] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.505395] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.530419] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.556494] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.583322] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.606655] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.631896] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.662158] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.692870] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.720202] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.747648] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.773896] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.798482] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.826644] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.858962] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.891872] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.919286] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.942746] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.964143] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 981.991789] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.014178] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.046829] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.078067] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.110567] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.133321] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.154255] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.177861] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.202526] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.224892] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.248113] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.269575] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.292428] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.316812] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.338731] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.360444] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.382381] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.406198] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.432825] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.459308] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.479967] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.505764] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.530679] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.553759] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.577619] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.601825] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.623882] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.645334] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.674659] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.699505] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.722135] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.749039] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.773401] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.798383] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.821842] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.842607] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.862717] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.886608] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.910563] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.938250] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.961689] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 982.990673] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.016509] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.037594] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.059203] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.083342] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.106982] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.129179] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.151918] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.173320] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.198971] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.222357] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.244194] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.267717] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.293653] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.315082] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.338272] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.359696] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.381768] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.403655] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.423973] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.444056] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.464440] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.487625] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.510565] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.531839] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.553349] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.574707] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.596755] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.618154] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.639275] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.662067] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.685514] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.705943] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.726939] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.747414] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.766762] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.786368] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.807196] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.830027] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.852419] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.874399] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.895262] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.916044] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.937832] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.961023] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 983.982237] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.004149] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.027388] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.050716] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.072346] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.094374] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.116502] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.143368] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.165099] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.189263] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.212712] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.235630] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.258690] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.282362] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.305359] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.327939] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.351959] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.375821] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.398654] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.421582] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.447374] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.466966] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.488910] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.511059] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.531898] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.552747] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.574593] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.595718] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.617365] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.640434] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.662390] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.684542] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.706542] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.727319] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.748858] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.769312] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.790538] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.811238] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.832651] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.854390] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.875864] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.899117] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.921016] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.941871] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.963195] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 984.984236] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.004315] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.024616] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.044778] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.064437] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.084340] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.103754] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.123670] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.144437] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.163799] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.187749] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.212286] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.236298] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.257198] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.280894] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.304615] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.325792] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.348609] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.372042] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.392810] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.414460] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.435378] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.457203] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.478336] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.500139] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.523515] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.547123] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.571936] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.594659] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.616228] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.636803] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.657213] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.680411] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.703310] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.725054] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.747953] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.771492] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.798416] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.820320] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.842668] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.864538] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.887410] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.910928] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.933568] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.956146] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.977558] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 985.999098] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.020745] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.042308] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.064688] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.086528] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.111950] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.136707] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.158945] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.182366] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.203490] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.225484] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.249621] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.277670] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.304332] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.326760] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.352610] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.384728] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.409410] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.434678] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.459057] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.481425] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.505195] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.537201] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.567317] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.593724] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.616140] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.639375] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.661792] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.683346] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.706366] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.728047] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.754218] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.775923] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.799386] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.823654] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.847184] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.868475] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.891414] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.919213] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.940700] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.963541] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 986.988977] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.010867] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.030125] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.049777] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.070884] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.092749] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.113517] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.135495] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.155772] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.176113] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.197251] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.219298] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.240953] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.261838] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.284216] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.304177] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.324704] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.345741] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.365796] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.385675] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.405949] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.426813] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.448775] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.468490] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.489129] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.510587] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.531824] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.553545] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.575174] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.597306] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.619362] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.639789] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.662664] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.684409] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.705489] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.726569] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.747191] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.768914] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.789396] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.810386] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.830763] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.851374] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.872427] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.893570] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.914105] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.937669] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.964462] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 987.991650] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.018847] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.045216] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.069281] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.090323] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.114327] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.137228] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.160442] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.187651] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.209299] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.231069] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.252084] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.274825] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.295558] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.323491] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.346564] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.370186] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.394424] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.422313] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.444726] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.471931] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.493893] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.515822] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.535354] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.559608] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.580220] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.601294] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.623537] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.650151] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.673360] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.695031] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.717640] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.740060] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.761402] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.783584] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.807936] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.831113] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.852919] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.874553] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.894931] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.914645] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.934825] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.954965] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.976823] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 988.998757] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.020499] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.042419] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.067172] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.090567] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.112583] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.133832] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.154828] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.181184] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.212192] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.234453] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.262869] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.285114] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.307485] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.329288] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.349600] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.369123] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.389292] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.409564] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.429532] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.449702] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.472810] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.493485] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.515784] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.537118] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.563645] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.588471] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.612101] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.638599] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.661243] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.682231] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.703980] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.724334] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.744808] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.763970] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.783268] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.805396] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.825603] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.850614] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.871148] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.897859] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.925981] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.952227] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.975478] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 989.996566] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.017737] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.039266] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.061943] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.083399] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.104178] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.126208] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.148244] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.172053] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.194163] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.217064] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.237526] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.257946] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.279910] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.302300] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.324342] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.345038] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.371648] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.392764] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.414879] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.443446] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.465270] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.488120] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.509695] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.530751] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.551957] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.573625] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.594655] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.615711] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.636498] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.656290] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.676564] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.698071] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.720586] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.742476] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.764052] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.784708] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.805501] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.829942] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.857864] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.878529] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.898138] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.923723] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.947757] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.969940] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 990.991578] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.013796] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.036294] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.056791] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.076653] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.097084] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.117482] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.137202] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.158357] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.178018] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.206095] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.227034] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.249046] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.270510] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.290762] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.311539] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.333507] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.355346] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.375607] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.395604] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.415032] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.433711] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.453719] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.476510] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.501649] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.522667] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.541754] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.560927] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.583426] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.604316] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.628555] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.649566] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.668437] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.688033] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.708310] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.730056] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.750014] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.769830] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.788892] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.807442] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.827308] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.857096] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.879525] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.908087] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.930060] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.949829] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.970020] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 991.992687] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.014509] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.036203] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.055703] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.077743] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.101570] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.123864] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.146333] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.168615] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.188273] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.208422] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.228421] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.257885] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.279251] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.302054] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.322248] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.346374] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.367094] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.386672] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.407447] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.430429] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.450979] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.472100] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.493070] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.514739] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.535369] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.555511] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.576692] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.597776] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.617849] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.638468] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.660659] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.681565] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.702484] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.722500] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.743557] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.765373] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.786114] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.807672] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.827453] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.855524] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.880057] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.900581] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.921656] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.942954] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.967291] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 992.987817] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.008470] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.032217] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.052762] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.074405] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.095958] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.116758] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.137635] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.159291] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.180477] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.202894] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.224162] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.248257] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.270030] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.290884] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.313562] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.337900] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.369174] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.389818] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.411052] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.431753] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.452105] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.473985] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.494556] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.515331] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.536048] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.556575] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.576591] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.598544] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.625462] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.653975] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.674761] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.694558] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.714504] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.733045] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.757072] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.777501] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.796794] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.816421] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.841741] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.862876] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.885328] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.905976] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.925834] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.949731] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.971130] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 993.992417] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.013561] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.034808] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.057620] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.081551] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.105464] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.132063] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.158770] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.180065] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.201546] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.222718] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.243209] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.264298] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.286714] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.309555] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.333984] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.356619] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.378015] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.398781] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.418995] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.439110] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.458849] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.479802] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.501367] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.529435] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.552242] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.573074] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.593402] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.613447] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.634041] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.655758] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.676492] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.697315] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.718865] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.740333] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.760860] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.783083] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.808449] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.831142] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.853459] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.874830] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.894211] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.914870] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.938217] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.961472] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 994.987034] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.008281] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.030934] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.054832] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.077948] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.105543] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.128241] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.150304] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.175386] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.198682] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.221430] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.244316] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.267515] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.293795] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.316145] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.336132] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.358270] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.380967] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.402753] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.424113] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.445550] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.469023] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.490552] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.512929] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.533542] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.554698] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.575109] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.595428] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.615141] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.634692] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.655746] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.675677] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.695215] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.714910] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.734155] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.754993] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.777055] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.798726] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.820919] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.845134] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.867948] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.889951] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.911208] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.931649] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.952445] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.974166] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 995.996185] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.019820] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.043074] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.071909] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.100665] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.120218] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.140729] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.167968] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.188074] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.210574] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.232469] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.255698] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.278516] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.301373] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.323642] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.344783] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.366207] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.386145] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.406263] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.426373] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.448080] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.469440] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.490585] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.511602] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.532093] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.552734] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.573817] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.594707] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.614638] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.636387] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.657917] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.680331] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.701116] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.722096] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.742646] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.763942] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.786892] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.809534] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.830169] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.854472] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.878879] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.901256] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.921817] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.943547] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.965695] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 996.990728] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.015376] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.039049] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.060717] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.083218] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.108686] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.132281] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.157576] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.180974] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.203732] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.226702] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.249265] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.270467] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.293033] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.315783] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.337791] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.359789] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.380710] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.401260] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.422579] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.443574] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.464539] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.487277] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.509986] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.532739] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.554818] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.576684] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.600043] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.622427] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.644194] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.667638] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.690788] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.711072] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.731950] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.756906] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.780957] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.802519] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.823559] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.846414] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.868697] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.892026] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.914950] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.936667] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.956720] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.975261] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 997.995412] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.016890] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.041171] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.064968] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.087495] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.114604] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.137143] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.158243] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.180248] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.201838] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.222910] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.243672] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.264058] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.283756] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.305182] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.325887] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.347466] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.369209] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.390066] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.410102] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.431898] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.457530] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.477274] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.500676] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.520677] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.540553] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.564340] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.584632] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.605649] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.626821] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.650199] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.671043] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.693221] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.717310] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.738609] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.759720] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.782358] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.805579] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.828159] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.850373] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.872686] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.894362] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.921855] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.946434] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.966476] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 998.986477] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.013769] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.036712] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.059121] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.079079] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.100485] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.122472] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.147019] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.168907] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.199334] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.222338] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.245288] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.266953] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.287022] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.310472] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.331237] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.352237] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.372558] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.393119] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.413649] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.434495] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.455284] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.475052] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.496278] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.517329] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.537674] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.559949] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.581081] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.601110] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.620658] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.640768] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.661941] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.681596] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.704148] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.729589] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.755688] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.776815] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.797273] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.818164] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.837595] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.858594] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.880993] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.905444] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.925983] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.952768] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.975568] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 999.999497] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 1000.021561] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 1000.046436] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 1000.070681] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 1000.093200] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 1000.114049] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 1000.134851] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 1000.155937] rw_seq_cst_vs_d (32450): drop_caches: 3 [ 1001.951262] Lustre: lustre-OST0000-osc-ffff95bc88a83800: disconnect after 24s idle [ 1001.953676] Lustre: Skipped 1 previous similar message [ 1002.336643] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 20:45:44 (1782866744) [ 1002.462672] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.549115] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.569539] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.605721] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.639323] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.659677] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.679488] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.780655] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.795906] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.820948] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.902297] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.918719] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1002.963084] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.242432] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.262845] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.308557] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.398291] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.443115] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.473181] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.494727] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.516867] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.534090] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.551561] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.569416] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.584924] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.609650] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.638875] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.661690] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.712601] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.737675] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.782880] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.810728] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.879544] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1003.976637] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1004.023898] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1004.233964] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1004.309404] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1004.653306] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1004.697175] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1004.725111] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1004.789551] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.227413] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.245096] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.262076] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.282212] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.301348] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.320532] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.346029] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.371619] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.398206] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.423505] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.450987] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.502503] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.565330] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.595777] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.618996] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.636997] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.659915] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.677345] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.694908] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.720675] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.743650] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.761073] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.784956] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.804098] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.835149] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.861087] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.881339] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.927192] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.948858] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.978476] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1005.998300] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.015490] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.032821] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.049360] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.070912] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.256213] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.284242] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.306163] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.336278] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.359939] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.497216] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.514717] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.532070] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.682323] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.737432] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.762183] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.787632] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.807942] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.836786] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.928547] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.952640] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.974319] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1006.995369] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.014414] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.032242] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.056900] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.082439] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.107237] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.216409] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.232047] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.250374] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.277097] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.397150] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.415108] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.438111] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.454831] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.472229] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.491297] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.581615] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.600942] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.617685] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.635775] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.658964] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.682371] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.749116] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.834626] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1007.880794] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.012433] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.054839] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.079525] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.096808] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.112764] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.134838] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.161950] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.204784] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.221743] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.244862] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.268686] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.294116] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.416949] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.539276] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.612123] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.635406] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.652858] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.806498] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.824612] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.843266] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1008.884115] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.040142] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.080680] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.137039] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.155364] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.261858] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.285810] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.302281] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.318038] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.334541] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.533073] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.578585] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.603076] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.628094] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.651783] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.669199] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.713569] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.874924] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1009.898482] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.024952] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.073781] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.102201] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.127865] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.346941] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.435130] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.497959] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.535678] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.558789] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.575518] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.702317] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.728929] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.749271] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.766548] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.785411] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.806639] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.907669] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.928114] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1010.968621] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.015633] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.040302] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.084758] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.103668] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.137816] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.159980] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.230320] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.249554] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.305093] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.349476] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.375693] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.396135] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.473952] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.830321] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.855378] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.898666] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.949332] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1011.973554] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.064180] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.091188] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.108851] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.162265] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.199602] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.226967] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.252540] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.270055] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.286371] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.303160] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.320792] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.337625] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.363527] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.390492] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.415952] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.442799] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.460074] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.509119] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.526628] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.573994] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.591437] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.609788] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.630062] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.665154] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.732891] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.751561] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.768545] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.783879] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.800031] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.815373] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.830440] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.846109] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.861980] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.878368] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.896533] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.912777] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.928283] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.946751] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.964694] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1012.991712] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.018635] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.049417] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.096119] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.111940] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.137452] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.175950] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.193538] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.212220] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.229180] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.247302] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.264417] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.282702] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.315288] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.378846] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.400298] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.424567] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.442139] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.461956] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.509306] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.540846] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.558477] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.600516] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.621779] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.670523] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.688900] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.705980] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.725582] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.748705] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.764664] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.782720] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.802619] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.819976] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.844176] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.928379] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.958076] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1013.979472] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.001321] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.200191] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.218743] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.237277] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.256351] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.281298] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.304860] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.323830] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.346667] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.363556] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.380460] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.397512] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.415996] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.434837] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.450866] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.472450] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.489308] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.504737] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.522101] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.543868] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.652889] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.687893] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.708292] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.734961] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.767691] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.817602] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.837741] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.857751] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.877470] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.894029] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.910746] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.973052] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1014.993621] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.017954] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.076167] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.099920] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.116860] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.133053] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.148855] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.164781] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.180457] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.197304] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.213957] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.230275] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.249385] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.266089] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.281453] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.614898] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.633990] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.659229] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.694886] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.717098] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.754348] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.770193] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.786269] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.803069] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.866862] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1015.888498] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.000160] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.026895] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.047520] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.150844] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.260932] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.345362] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.362452] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.469311] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.595537] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.612502] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.627985] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.642385] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.657063] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.675343] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.699129] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.723415] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.800505] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.826458] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.843562] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.862258] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.884537] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.902288] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.918867] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1016.938859] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.088420] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.103842] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.219529] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.242547] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.264345] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.282515] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.300397] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.317678] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.335953] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.353345] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.370905] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.410829] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.430816] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.447915] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.488687] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.508072] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.549790] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.572530] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.588436] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.604627] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.621597] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.684435] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.704812] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.723822] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.779055] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.799283] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.816586] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.834057] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.859686] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.905406] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.927779] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.959082] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1017.982815] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.002989] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.020163] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.037048] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.058788] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.076785] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.097931] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.144622] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.162553] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.178351] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.195223] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.214912] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.231626] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.249781] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.269743] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.351856] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.377107] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.396259] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.443458] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.489239] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.517107] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.546792] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.582906] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.617813] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.639912] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.761916] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.793214] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.852967] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.876915] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.929260] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.965448] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1018.990758] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.031194] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.052410] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.120585] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.206430] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.238777] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.260330] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.301892] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.323381] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.342416] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.358992] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.374601] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.390135] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.409864] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.428026] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.449942] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.486067] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.511571] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.599576] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.631651] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.657337] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.680502] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.697213] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.716939] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.735665] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.769266] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.798456] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.905803] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.923541] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.969056] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1019.990958] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.017592] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.063682] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.088710] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.110709] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.132820] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.183747] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.218347] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.272267] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.328802] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.378264] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.451476] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.472386] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.508255] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.531595] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.572706] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.598380] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.630239] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.658005] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.688010] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.721177] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.748297] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.779866] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.805761] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.862483] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.892029] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.945955] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1020.971854] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.020713] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.040105] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.058837] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.112919] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.225472] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.250105] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.265889] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.281510] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.298278] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.321431] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.338106] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.354790] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.371525] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.388898] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.489521] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.582563] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.643411] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.665069] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.721428] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.747507] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.770632] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.793353] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.814652] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.834851] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1021.913817] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.009279] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.068232] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.094965] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.125761] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.173906] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.211915] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.241183] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.301375] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.325799] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.347139] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.365995] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1022.382829] rw_seq_cst_vs_d (33043): drop_caches: 3 [ 1025.295332] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 20:46:07 (1782866767) [ 1028.217225] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 20:46:10 (1782866770) [ 1031.038342] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 20:46:13 (1782866773) [ 1039.643913] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 20:46:22 (1782866782) [ 1039.886902] bash (35515): drop_caches: 3 [ 1043.012894] bash (35515): drop_caches: 3 [ 1046.254410] bash (35515): drop_caches: 3 [ 1049.340904] bash (35515): drop_caches: 3 [ 1053.349540] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 20:46:35 (1782866795) [ 1058.654225] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 20:46:41 (1782866801) [ 1070.974308] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 20:46:53 (1782866813) [ 1074.267901] Lustre: DEBUG MARKER: loop 5 [ 1076.098617] Lustre: DEBUG MARKER: loop 10 [ 1077.732009] Lustre: DEBUG MARKER: loop 15 [ 1079.486604] Lustre: DEBUG MARKER: loop 20 [ 1082.332078] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 20:47:04 (1782866824) [ 1085.070517] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 20:47:07 (1782866827) [ 1087.834555] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 20:47:10 (1782866830) [ 1104.351286] Lustre: lustre-OST0000-osc-ffff95bc851fe000: disconnect after 22s idle [ 1109.471234] Lustre: lustre-OST0001-osc-ffff95bc851fe000: disconnect after 21s idle [ 1109.473547] Lustre: Skipped 2 previous similar messages [ 1151.460395] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 20:48:13 (1782866893) [ 1153.818317] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 20:48:16 (1782866896) [ 1155.930713] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 20:48:18 (1782866898) [ 1158.584740] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 20:48:20 (1782866900) [ 1160.859752] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 20:48:23 (1782866903) [ 1163.802442] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 20:48:26 (1782866906) [ 1168.333429] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 20:48:30 (1782866910) [ 1171.438044] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1171.960940] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 20:48:34 (1782866914) [ 1176.214690] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 20:48:38 (1782866918) [ 1176.341306] Lustre: *** cfs_fail_loc=314, val=0*** [ 1177.375303] Lustre: *** cfs_fail_loc=314, val=0*** [ 1177.378441] Lustre: Skipped 2 previous similar messages [ 1179.860617] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 20:48:42 (1782866922) [ 1185.196667] Lustre: *** cfs_fail_loc=314, val=0*** [ 1185.219832] LustreError: lustre-OST0000-osc-ffff95bc88a83800: operation ldlm_enqueue to node 192.168.203.101@tcp failed: rc = -107 [ 1185.223503] Lustre: lustre-OST0000-osc-ffff95bc88a83800: Connection to lustre-OST0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1185.230111] LustreError: lustre-OST0000-osc-ffff95bc88a83800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1185.235738] LustreError: 46499:0:(ldlm_resource.c:1207:ldlm_resource_complain()) lustre-OST0000-osc-ffff95bc88a83800: namespace resource [0x280000401:0x38:0x0].0x0 (ffff95bca19fa700) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1185.241564] Lustre: lustre-OST0000-osc-ffff95bc88a83800: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 1187.655337] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 20:48:50 (1782866930) [ 1187.728964] LustreError: 47089:0:(file.c:818:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1190.751095] LustreError: 47089:0:(file.c:818:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1193.132387] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 20:48:55 (1782866935) [ 1196.132237] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 20:48:58 (1782866938) [ 1199.318881] Lustre: DEBUG MARKER: == sanityn test 32b: lockless i/o ======================== 20:49:01 (1782866941) [ 1200.051125] Lustre: DEBUG MARKER: SKIP: sanityn test_32b max_nolock_bytes is removed >= 2.17.53 [ 1200.744313] Lustre: DEBUG MARKER: == sanityn test 32c: contention detection ================ 20:49:03 (1782866943) [ 1213.458531] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1214.042551] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 20:49:16 (1782866956) [ 1214.745649] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1215.504176] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 20:49:17 (1782866957) [ 1216.993353] Lustre: lustre-MDT0000-mdc-ffff95bc851fe000: Connection to lustre-MDT0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1217.001695] Lustre: Skipped 1 previous similar message [ 1222.112209] LustreError: MGC192.168.203.101@tcp: Connection to MGS (at 192.168.203.101@tcp) was lost; in progress operations using this service will fail [ 1222.118772] Lustre: Evicted from MGS (at 192.168.203.101@tcp) after server handle changed from 0x3a9fb74de9bc456e to 0x3a9fb74de9eb66dc [ 1222.122784] Lustre: MGC192.168.203.101@tcp: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 1225.670515] Lustre: lustre-MDT0000-mdc-ffff95bc851fe000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 1227.231558] Lustre: lustre-OST0001-osc-ffff95bc851fe000: disconnect after 20s idle [ 1236.760539] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 20:49:39 (1782866979) [ 1237.471231] Lustre: lustre-OST0000-osc-ffff95bc851fe000: disconnect after 21s idle [ 1237.473292] Lustre: Skipped 1 previous similar message [ 1252.831181] Lustre: lustre-OST0000-osc-ffff95bc88a83800: disconnect after 21s idle [ 1255.098777] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 20:49:57 (1782866997) [ 1260.493759] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 20:50:02 (1782867002) [ 1283.551302] Lustre: lustre-OST0001-osc-ffff95bc851fe000: disconnect after 23s idle [ 1314.273982] Lustre: lustre-OST0000-osc-ffff95bc851fe000: Connection to lustre-OST0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1314.284500] LustreError: lustre-OST0000-osc-ffff95bc851fe000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1314.292067] LustreError: lustre-OST0000-osc-ffff95bc88a83800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1314.292333] Lustre: lustre-OST0000-osc-ffff95bc851fe000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 1314.304152] Lustre: Skipped 2 previous similar messages [ 1323.445595] Lustre: lustre-OST0001-osc-ffff95bc851fe000: Connection to lustre-OST0001 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1323.452188] Lustre: Skipped 1 previous similar message [ 1323.455657] LustreError: lustre-OST0001-osc-ffff95bc851fe000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1323.462042] Lustre: lustre-OST0001-osc-ffff95bc851fe000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 1334.751198] Lustre: lustre-OST0000-osc-ffff95bc851fe000: disconnect after 20s idle [ 1334.753353] Lustre: Skipped 1 previous similar message [ 1336.335635] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff95bc851fe000.ost_server_uuid 50 [ 1336.831865] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff95bc851fe000.ost_server_uuid in IDLE state after 0 sec [ 1338.591151] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95bc851fe000.ost_server_uuid 50 [ 1339.047634] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95bc851fe000.ost_server_uuid in FULL state after 0 sec [ 1341.421113] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff95bc851fe000.ost_server_uuid 50 [ 1341.897245] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff95bc851fe000.ost_server_uuid in IDLE state after 0 sec [ 1343.635367] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95bc851fe000.ost_server_uuid 50 [ 1344.119071] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95bc851fe000.ost_server_uuid in FULL state after 0 sec [ 1347.784249] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff95bc851fe000.ost_server_uuid 50 [ 1348.270142] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff95bc851fe000.ost_server_uuid in IDLE state after 0 sec [ 1350.235058] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95bc851fe000.ost_server_uuid 50 [ 1350.714907] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95bc851fe000.ost_server_uuid in IDLE state after 0 sec [ 1351.224680] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 20:51:33 (1782867093) [ 1352.131686] Lustre: DEBUG MARKER: Race attempt 0 [ 1353.280312] Lustre: *** cfs_fail_loc=317, val=2147484440*** [ 1353.282546] LustreError: 59803:0:(ldlm_lock.c:915:ldlm_lock_decref_internal()) cfs_race id 318 sleeping [ 1353.661367] Lustre: DEBUG MARKER: Wait for 59670 59803 for 60 sec... [ 1358.303072] LustreError: 59803:0:(ldlm_lock.c:915:ldlm_lock_decref_internal()) cfs_fail_race id 318 awake: rc=0 [ 1416.246522] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 20:52:38 (1782867158) [ 1422.141878] Lustre: DEBUG MARKER: start test - cycle (0) [ 1441.885181] Lustre: DEBUG MARKER: start test - cycle (1) [ 1462.364882] Lustre: DEBUG MARKER: start test - cycle (2) [ 1479.314443] Lustre: DEBUG MARKER: start test - cycle (3) [ 1497.967962] Lustre: DEBUG MARKER: start test - cycle (4) [ 1513.769028] Lustre: DEBUG MARKER: start test - cycle (5) [ 1529.100597] Lustre: DEBUG MARKER: start test - cycle (6) [ 1549.118625] Lustre: DEBUG MARKER: start test - cycle (7) [ 1566.127055] Lustre: DEBUG MARKER: start test - cycle (8) [ 1584.749052] Lustre: DEBUG MARKER: start test - cycle (9) [ 1602.130626] Lustre: DEBUG MARKER: start test - cycle (10) [ 1623.468135] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 20:56:05 (1782867365) [ 1631.711318] Lustre: lustre-OST0000-osc-ffff95bc88a83800: disconnect after 21s idle [ 1631.716786] Lustre: Skipped 2 previous similar messages [ 1643.834252] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 20:56:26 (1782867386) [ 1646.591191] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 20:56:28 (1782867388) [ 1650.783907] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 20:56:33 (1782867393) [ 1654.687344] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 20:56:36 (1782867396) [ 1654.791034] Lustre: *** cfs_fail_loc=411, val=0*** [ 1657.679781] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1658.435202] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 20:56:40 (1782867400) [ 1667.577309] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 20:56:49 (1782867409) [ 1675.207615] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 20:56:57 (1782867417) [ 1682.815864] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 20:57:05 (1782867425) [ 1689.909885] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 20:57:12 (1782867432) [ 1694.708954] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 20:57:17 (1782867437) [ 1699.486159] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 20:57:21 (1782867441) [ 1704.308326] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 20:57:26 (1782867446) [ 1709.115435] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 20:57:31 (1782867451) [ 1713.896869] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 20:57:36 (1782867456) [ 1718.747616] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 20:57:41 (1782867461) [ 1723.880946] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 20:57:46 (1782867466) [ 1728.940341] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 20:57:51 (1782867471) [ 2353.631311] Lustre: lustre-OST0000-osc-ffff95bc88a83800: disconnect after 24s idle [ 2353.636597] Lustre: Skipped 4 previous similar messages [ 2491.174298] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 21:10:33 (1782868233) [ 2498.171817] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 21:10:40 (1782868240) [ 2505.355571] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 21:10:47 (1782868247) [ 2512.509569] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 21:10:54 (1782868254) [ 2519.619887] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 21:11:01 (1782868261) [ 2526.872796] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 21:11:09 (1782868269) [ 2534.205457] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 21:11:16 (1782868276) [ 2541.376321] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 21:11:23 (1782868283) [ 2548.667313] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 21:11:30 (1782868290) [ 2587.334866] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 21:12:09 (1782868329) [ 2594.599986] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 21:12:16 (1782868336) [ 2601.542444] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 21:12:23 (1782868343) [ 2608.562133] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 21:12:30 (1782868350) [ 2615.502795] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 21:12:37 (1782868357) [ 2622.128596] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 21:12:44 (1782868364) [ 2628.919919] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 21:12:51 (1782868371) [ 2635.768745] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 21:12:58 (1782868378) [ 2642.286278] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 21:13:04 (1782868384) [ 2660.831213] Lustre: lustre-OST0000-osc-ffff95bc851fe000: disconnect after 23s idle [ 2660.835292] Lustre: Skipped 3 previous similar messages [ 2701.711112] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 21:14:03 (1782868443) [ 3294.671466] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 21:23:56 (1782869036) [ 3301.670855] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 21:24:03 (1782869043) [ 3305.952203] Lustre: lustre-OST0000-osc-ffff95bc88a83800: disconnect after 21s idle [ 3305.956194] Lustre: Skipped 1 previous similar message [ 3308.593691] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 21:24:10 (1782869050) [ 3315.645702] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 21:24:17 (1782869057) [ 3322.399974] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 21:24:24 (1782869064) [ 3329.511483] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 21:24:31 (1782869071) [ 3336.173783] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 21:24:38 (1782869078) [ 3342.973813] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 21:24:45 (1782869085) [ 3350.122813] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 21:24:52 (1782869092) [ 3357.448862] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 21:24:59 (1782869099) [ 3423.095958] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 21:26:05 (1782869165) [ 3430.221763] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 21:26:12 (1782869172) [ 3437.237335] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 21:26:19 (1782869179) [ 3443.991154] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 21:26:26 (1782869186) [ 3450.830410] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 21:26:33 (1782869193) [ 3456.346559] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 21:26:38 (1782869198) [ 3460.962761] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 21:26:43 (1782869203) [ 3465.149689] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 21:26:47 (1782869207) [ 3469.770470] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 21:26:52 (1782869212) [ 3976.671297] Lustre: lustre-OST0001-osc-ffff95bc851fe000: disconnect after 20s idle [ 3976.675856] Lustre: Skipped 4 previous similar messages [ 4044.481533] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 21:36:26 (1782869786) [ 4051.292591] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 21:36:33 (1782869793) [ 4058.142135] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 21:36:40 (1782869800) [ 4065.167184] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 21:36:47 (1782869807) [ 4072.058892] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 21:36:54 (1782869814) [ 4079.031547] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 21:37:01 (1782869821) [ 4085.942748] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 21:37:08 (1782869828) [ 4092.759864] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 21:37:15 (1782869835) [ 4099.536294] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 21:37:21 (1782869841) [ 4105.962542] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 21:37:28 (1782869848) [ 4112.691615] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 21:37:35 (1782869855) [ 4120.411935] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 21:37:42 (1782869862) [ 4127.041215] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 21:37:49 (1782869869) [ 4133.445472] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 21:37:55 (1782869875) [ 4140.504454] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 21:38:02 (1782869882) [ 4147.569731] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 21:38:09 (1782869889) [ 4156.528343] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 21:38:18 (1782869898) [ 4156.687708] LustreError: 13875:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4158.775157] LustreError: 13875:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4164.822850] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 21:38:27 (1782869907) [ 4169.971794] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 21:38:32 (1782869912) [ 4170.119525] LustreError: 241711:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4174.183154] LustreError: 241711:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4174.197291] LustreError: 241711:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4178.255144] LustreError: 241711:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4178.279087] LustreError: 241717:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4182.343142] LustreError: 241717:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4185.712991] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 21:38:47 (1782869927) [ 4193.750584] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 21:38:55 (1782869935) [ 4198.145763] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 21:39:00 (1782869940) [ 4203.521787] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 21:39:05 (1782869945) [ 4229.080810] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 21:39:31 (1782869971) [ 4236.908304] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 21:39:39 (1782869979) [ 4244.483988] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 21:39:46 (1782869986) [ 4258.112055] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 21:40:00 (1782870000) [ 4268.370741] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 21:40:10 (1782870010) [ 4282.260193] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 21:40:24 (1782870024) [ 4296.410723] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 21:40:38 (1782870038) [ 4312.216599] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 21:40:54 (1782870054) [ 4320.277826] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 21:41:02 (1782870062) [ 4349.008537] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 21:41:31 (1782870091) [ 4351.727345] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4355.488852] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 21:41:37 (1782870097) [ 4359.041320] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 21:41:41 (1782870101) [ 4362.245089] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 21:41:44 (1782870104) [ 4365.331430] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 21:41:47 (1782870107) [ 4382.589364] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 21:42:04 (1782870124) [ 4400.180926] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 21:42:22 (1782870142) [ 4403.657097] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 21:42:25 (1782870145) [ 4407.225819] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 21:42:29 (1782870149) [ 4410.366564] LustreError: lustre-MDT0000-mdc-ffff95bc851fe000: operation ldlm_enqueue to node 192.168.203.101@tcp failed: rc = -35 [ 4410.372987] LustreError: Skipped 1 previous similar message [ 4414.580775] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 21:42:36 (1782870156) [ 4414.813659] LustreError: 2390:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4416.903127] LustreError: 2390:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 4423.115520] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 21:42:45 (1782870165) [ 4483.009738] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 21:43:45 (1782870225) [ 4487.039334] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 21:43:49 (1782870229) [ 4492.649743] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 21:43:54 (1782870234) [ 4499.592099] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 21:44:01 (1782870241) [ 4506.657344] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 21:44:08 (1782870248) [ 4516.868775] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 21:44:19 (1782870259) [ 4527.488687] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 21:44:29 (1782870269) [ 4532.834659] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 21:44:35 (1782870275) [ 4538.624150] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 21:44:40 (1782870280) [ 4549.185655] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 21:44:51 (1782870291) [ 4592.732603] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 21:45:34 (1782870334) [ 4616.671307] Lustre: lustre-OST0001-osc-ffff95bc851fe000: disconnect after 22s idle [ 4616.676025] Lustre: Skipped 9 previous similar messages [ 4710.240665] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 21:47:32 (1782870452) [ 4827.512196] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 21:49:29 (1782870569) [ 5121.294183] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 21:54:23 (1782870863) [ 5152.116172] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 21:54:54 (1782870894) [ 5183.030995] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 21:55:25 (1782870925) [ 5209.148607] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 21:55:51 (1782870951) [ 5236.191424] Lustre: lustre-OST0001-osc-ffff95bc851fe000: disconnect after 20s idle [ 5236.196331] Lustre: Skipped 9 previous similar messages [ 5271.091156] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 21:56:53 (1782871013) [ 5326.792491] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 21:57:48 (1782871068) [ 5424.047861] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 21:59:26 (1782871166) [ 5425.582819] Lustre: Unmounted lustre-client [ 5426.597159] Lustre: Unmounted lustre-client [ 5502.454246] Lustre: Mounted lustre-client [ 5504.298984] Lustre: Mounted lustre-client [ 5505.907050] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5571.846859] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 22:01:53 (1782871313) [ 5581.812572] Lustre: DEBUG MARKER: == sanityn test 77kj: Verify nodemap support for NRS TBF rule ========================================================== 22:02:03 (1782871323) [ 5682.583501] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 22:03:44 (1782871424) [ 5687.581670] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 22:03:49 (1782871429) [ 5739.530284] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 22:04:41 (1782871481) [ 5789.076316] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 22:05:31 (1782871531) [ 5793.992802] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 22:05:36 (1782871536) [ 5836.086648] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 22:06:18 (1782871578) [ 5847.907181] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 22:06:30 (1782871590) [ 5890.163756] Lustre: DEBUG MARKER: == sanityn test 77s: Check TBF LRU shrinker ============== 22:07:12 (1782871632) [ 5901.435473] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 22:07:23 (1782871643) [ 5904.569959] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 22:07:26 (1782871646) [ 5914.079182] Lustre: lustre-OST0000-osc-ffff95bc90316800: disconnect after 20s idle [ 5914.081637] Lustre: Skipped 14 previous similar messages [ 5917.434525] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 22:07:39 (1782871659) [ 5921.040929] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 22:07:43 (1782871663) [ 5921.414814] LustreError: 314659:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x27:0x0]: rc = -5 [ 5921.418582] LustreError: 314659:0:(llite_lib.c:3753:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5922.041414] LustreError: 314710:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x789:0x0]: rc = -5 [ 5922.046047] LustreError: 314710:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 9 previous similar messages [ 5922.049257] LustreError: 314710:0:(llite_lib.c:3753:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5922.053426] LustreError: 314710:0:(llite_lib.c:3753:ll_prep_inode()) Skipped 9 previous similar messages [ 5923.066307] LustreError: 314782:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x7a6:0x0]: rc = -5 [ 5923.070156] LustreError: 314782:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 13 previous similar messages [ 5923.073155] LustreError: 314782:0:(llite_lib.c:3753:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5923.076247] LustreError: 314782:0:(llite_lib.c:3753:ll_prep_inode()) Skipped 13 previous similar messages [ 5925.101779] LustreError: 314962:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x83:0x0]: rc = -5 [ 5925.107866] LustreError: 314962:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 34 previous similar messages [ 5925.112489] LustreError: 314962:0:(llite_lib.c:3753:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5925.116032] LustreError: 314962:0:(llite_lib.c:3753:ll_prep_inode()) Skipped 34 previous similar messages [ 5929.122848] LustreError: 315342:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x868:0x0]: rc = -5 [ 5929.126929] LustreError: 315342:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 129 previous similar messages [ 5929.129858] LustreError: 315342:0:(llite_lib.c:3753:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5929.132531] LustreError: 315342:0:(llite_lib.c:3753:ll_prep_inode()) Skipped 129 previous similar messages [ 5937.128712] LustreError: 316112:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x99a:0x0]: rc = -5 [ 5937.133651] LustreError: 316112:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 302 previous similar messages [ 5937.136523] LustreError: 316112:0:(llite_lib.c:3753:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5937.139776] LustreError: 316112:0:(llite_lib.c:3753:ll_prep_inode()) Skipped 302 previous similar messages [ 6043.593839] LustreError: 316565:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0xa24:0x0]: rc = -5 [ 6043.597100] LustreError: 316565:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 169 previous similar messages [ 6043.599441] LustreError: 316565:0:(llite_lib.c:3753:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6043.601895] LustreError: 316565:0:(llite_lib.c:3753:ll_prep_inode()) Skipped 169 previous similar messages [ 6043.604246] LustreError: lustre-MDT0000-mdc-ffff95bc90316800: operation mds_getattr_lock to node 192.168.203.101@tcp failed: rc = -107 [ 6043.608236] Lustre: lustre-MDT0000-mdc-ffff95bc90316800: Connection to lustre-MDT0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6043.614530] LustreError: lustre-MDT0000-mdc-ffff95bc90316800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6043.618586] LustreError: 316562:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff95bc90316800: inode [0x2000013a1:0xa23:0x0] mdc close failed: rc = -108 [ 6043.622542] LustreError: 316562:0:(file.c:6088:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 6043.626732] Lustre: lustre-MDT0000-mdc-ffff95bc90316800: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6055.871760] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 22:09:58 (1782871798) [ 6058.102232] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 22:10:00 (1782871800) [ 6102.826774] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 22:10:45 (1782871845) [ 6103.321425] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6103.906834] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 22:10:46 (1782871846) [ 6144.684732] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 22:11:27 (1782871887) [ 6146.984691] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 22:11:29 (1782871889) [ 6269.030820] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 22:13:31 (1782872011) [ 6276.220426] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 22:13:38 (1782872018) [ 6278.857727] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 22:13:41 (1782872021) [ 6461.264412] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 22:16:43 (1782872203) [ 6548.959153] Lustre: lustre-OST0001-osc-ffff95bc90316800: disconnect after 22s idle [ 6548.961991] Lustre: Skipped 5 previous similar messages [ 6643.550283] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 22:19:46 (1782872386) [ 6645.624150] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 22:19:48 (1782872388) [ 6654.611712] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 22:19:57 (1782872397) [ 6654.674895] Lustre: DEBUG MARKER: write [ 6654.689966] LustreError: 295777:0:(ldlm_request.c:1533:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6656.694902] Lustre: DEBUG MARKER: kill 377692 [ 6656.696639] LustreError: 377692:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6659.791108] LustreError: 295777:0:(ldlm_request.c:1533:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 6662.735091] LustreError: 377692:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 6665.176842] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 22:20:07 (1782872407) [ 6667.387412] LustreError: 378305:0:(rw.c:1865:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 6677.479093] LustreError: 378305:0:(rw.c:1865:ll_readpage()) cfs_fail_timeout id 1422 awake [ 6680.365453] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 22:20:22 (1782872422) [ 6680.484632] LustreError: 378892:0:(rw.c:2112:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6682.567097] LustreError: 378892:0:(rw.c:2112:ll_readpage()) cfs_fail_timeout interrupted [ 6688.484285] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 22:20:30 (1782872430) [ 6688.970995] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6689.531722] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 22:20:31 (1782872431) [ 6692.162824] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 22:20:34 (1782872434) [ 6694.295529] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 22:20:36 (1782872436) [ 6696.398205] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 22:20:38 (1782872438) [ 6698.531200] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 22:20:40 (1782872440) [ 6700.645792] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 22:20:43 (1782872443) [ 6703.024759] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 22:20:45 (1782872445) [ 6706.498772] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 22:20:48 (1782872448) [ 6709.255744] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 22:20:51 (1782872451) [ 6709.901411] Lustre: *** cfs_fail_loc=415, val=0*** [ 6716.773743] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 22:20:59 (1782872459) [ 6736.594149] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 22:21:18 (1782872478) [ 6736.725540] LustreError: 295777:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6736.730144] LustreError: 295777:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 6741.727151] LustreError: 295777:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 6751.927125] LustreError: 295777:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 6751.932723] LustreError: 295777:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 6 previous similar messages [ 6759.585176] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 22:21:41 (1782872501) [ 6762.166375] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 22:21:44 (1782872504) [ 6765.119713] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 22:21:47 (1782872507) [ 6767.615617] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 22:21:50 (1782872510) [ 6771.898403] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 22:21:54 (1782872514) [ 6780.113890] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 22:22:02 (1782872522) [ 6780.273850] LustreError: 389624:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6780.277021] LustreError: 389624:0:(osc_request.c:2989:osc_build_rpc()) Skipped 6 previous similar messages [ 6784.335126] LustreError: 389624:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6784.337307] LustreError: 389624:0:(osc_request.c:2989:osc_build_rpc()) Skipped 2 previous similar messages [ 6786.418381] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 22:22:08 (1782872528) [ 6787.899948] Lustre: Unmounted lustre-client [ 6788.587690] Lustre: Unmounted lustre-client [ 6789.083410] Lustre: DEBUG MARKER: Iteration 0 [ 6789.180123] LustreError: 390516:0:(llite_lib.c:1389:ll_fill_super()) cfs_race id 1417 sleeping [ 6789.182580] LustreError: 390517:0:(llite_lib.c:1389:ll_fill_super()) cfs_fail_race id 1417 waking [ 6789.185497] LustreError: 390516:0:(llite_lib.c:1389:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6789.218749] Lustre: Mounted lustre-client [ 6789.758503] Lustre: Unmounted lustre-client [ 6790.688677] Key type lgssc unregistered [ 6790.801538] LNet: 390858:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6790.803856] LNetError: 390858:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6790.812833] LNet: Removed LNI 192.168.203.1@tcp [ 6791.108120] Key type .llcrypt unregistered [ 6791.109268] Key type ._llcrypt unregistered [ 6791.409347] Key type ._llcrypt registered [ 6791.415059] Key type .llcrypt registered [ 6791.675677] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6791.680557] alg: No test for adler32 (adler32-zlib) [ 6792.641568] Lustre: Lustre: Build Version: 2.17.54_83_g326f97e [ 6792.904408] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6794.511125] Key type lgssc registered [ 6794.995367] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6798.878057] Lustre: DEBUG MARKER: Iteration 1 [ 6798.971778] LustreError: 391690:0:(llite_lib.c:1389:ll_fill_super()) cfs_race id 1417 sleeping [ 6798.972055] LustreError: 391691:0:(llite_lib.c:1389:ll_fill_super()) cfs_fail_race id 1417 waking [ 6798.975752] LustreError: 391690:0:(llite_lib.c:1389:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6800.013509] Lustre: Mounted lustre-client [ 6800.014658] Lustre: Skipped 1 previous similar message [ 6800.493068] Lustre: Unmounted lustre-client [ 6801.339649] Key type lgssc unregistered [ 6801.450477] LNet: 392032:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6801.452668] LNetError: 392032:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6801.460638] LNet: Removed LNI 192.168.203.1@tcp [ 6801.702113] Key type .llcrypt unregistered [ 6801.703165] Key type ._llcrypt unregistered [ 6802.033912] Key type ._llcrypt registered [ 6802.034985] Key type .llcrypt registered [ 6802.136554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6802.142037] alg: No test for adler32 (adler32-zlib) [ 6802.993281] Lustre: Lustre: Build Version: 2.17.54_83_g326f97e [ 6803.075304] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6804.655122] Key type lgssc registered [ 6804.991683] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6808.204477] Lustre: DEBUG MARKER: Iteration 2 [ 6808.297775] LustreError: 392863:0:(llite_lib.c:1389:ll_fill_super()) cfs_race id 1417 sleeping [ 6808.298091] LustreError: 392864:0:(llite_lib.c:1389:ll_fill_super()) cfs_fail_race id 1417 waking [ 6808.302397] LustreError: 392863:0:(llite_lib.c:1389:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6809.338110] Lustre: Mounted lustre-client [ 6809.339199] Lustre: Skipped 1 previous similar message [ 6809.958321] Lustre: Unmounted lustre-client [ 6810.805955] Key type lgssc unregistered [ 6810.905526] LNet: 393205:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6810.907669] LNetError: 393205:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6810.915686] LNet: Removed LNI 192.168.203.1@tcp [ 6811.146107] Key type .llcrypt unregistered [ 6811.147203] Key type ._llcrypt unregistered [ 6811.464194] Key type ._llcrypt registered [ 6811.465356] Key type .llcrypt registered [ 6811.597814] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6811.602339] alg: No test for adler32 (adler32-zlib) [ 6812.448809] Lustre: Lustre: Build Version: 2.17.54_83_g326f97e [ 6812.529656] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6814.111150] Key type lgssc registered [ 6814.441082] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6817.816265] Lustre: DEBUG MARKER: Iteration 3 [ 6817.935299] LustreError: 394038:0:(llite_lib.c:1389:ll_fill_super()) cfs_race id 1417 sleeping [ 6817.935330] LustreError: 394037:0:(llite_lib.c:1389:ll_fill_super()) cfs_fail_race id 1417 waking [ 6817.940396] LustreError: 394038:0:(llite_lib.c:1389:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6818.980376] Lustre: Mounted lustre-client [ 6819.597490] Lustre: Unmounted lustre-client [ 6819.598531] Lustre: Skipped 1 previous similar message [ 6820.595726] Key type lgssc unregistered [ 6820.715551] LNet: 394380:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6820.717819] LNetError: 394380:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6820.727331] LNet: Removed LNI 192.168.203.1@tcp [ 6820.974130] Key type .llcrypt unregistered [ 6820.975776] Key type ._llcrypt unregistered [ 6821.249176] Key type ._llcrypt registered [ 6821.250492] Key type .llcrypt registered [ 6821.441816] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6821.446603] alg: No test for adler32 (adler32-zlib) [ 6822.289129] Lustre: Lustre: Build Version: 2.17.54_83_g326f97e [ 6822.368280] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6823.943122] Key type lgssc registered [ 6824.272258] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6828.355757] Lustre: Mounted lustre-client [ 6830.650690] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 22:22:53 (1782872573) [ 6846.431142] Lustre: 395718:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782872573/real 1782872573] req@ffff95bcb4238a80 x1869477382727552/t0(0) o36->lustre-MDT0000-mdc-ffff95bca0a77800@192.168.203.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1782872589 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6846.437880] Lustre: lustre-MDT0000-mdc-ffff95bca0a77800: Connection to lustre-MDT0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6846.445875] Lustre: lustre-MDT0000-mdc-ffff95bca0a77800: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6862.815135] Lustre: 395718:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782872589/real 1782872589] req@ffff95bcb4238a80 x1869477382727552/t0(0) o36->lustre-MDT0000-mdc-ffff95bca0a77800@192.168.203.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1782872605 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6862.821978] Lustre: lustre-MDT0000-mdc-ffff95bca0a77800: Connection to lustre-MDT0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6862.831852] Lustre: lustre-MDT0000-mdc-ffff95bca0a77800: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6878.175178] Lustre: 395718:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782872605/real 1782872605] req@ffff95bcb4238a80 x1869477382727552/t0(0) o36->lustre-MDT0000-mdc-ffff95bca0a77800@192.168.203.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1782872621 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6878.184260] Lustre: lustre-MDT0000-mdc-ffff95bca0a77800: Connection to lustre-MDT0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6878.193635] Lustre: lustre-MDT0000-mdc-ffff95bca0a77800: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6894.559152] Lustre: 395718:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782872621/real 1782872621] req@ffff95bcb4238a80 x1869477382727552/t0(0) o36->lustre-MDT0000-mdc-ffff95bca0a77800@192.168.203.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1782872637 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6894.566622] Lustre: lustre-MDT0000-mdc-ffff95bca0a77800: Connection to lustre-MDT0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6894.576365] Lustre: lustre-MDT0000-mdc-ffff95bca0a77800: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6895.169446] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 22:23:57 (1782872637) [ 6901.661767] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 22:24:03 (1782872643) [ 6905.240159] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 22:24:07 (1782872647) [ 6907.598187] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 22:24:09 (1782872649) [ 6915.096827] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 22:24:17 (1782872657) [ 6927.312807] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 22:24:29 (1782872669) [ 6929.557388] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 22:24:32 (1782872672) [ 6929.632715] LustreError: 400488:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 6931.719065] LustreError: 400488:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 6933.737193] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 22:24:36 (1782872676) [ 6951.391223] Lustre: 394573:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782872678/real 1782872678] req@ffff95bcbbfbaa00 x1869477383764864/t0(0) o4->lustre-OST0000-osc-ffff95bca0a77800@192.168.203.101@tcp:6/4 lens 4584/448 e 0 to 1 dl 1782872694 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6951.403069] Lustre: lustre-OST0000-osc-ffff95bca0a77800: Connection to lustre-OST0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6967.775098] Lustre: 394572:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782872694/real 1782872694] req@ffff95bcbbfba680 x1869477383765248/t0(0) o4->lustre-OST0000-osc-ffff95bca0a77800@192.168.203.101@tcp:6/4 lens 4584/448 e 0 to 1 dl 1782872710 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6967.775164] Lustre: lustre-OST0000-osc-ffff95bca0a77800: Connection to lustre-OST0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6967.783053] Lustre: 394572:0:(client.c:2489:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 6967.792198] Lustre: lustre-OST0000-osc-ffff95bca0a77800: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6983.137411] Lustre: lustre-OST0000-osc-ffff95bca0a77800: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6999.519182] Lustre: 394574:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782872726/real 1782872726] req@ffff95bcbc602300 x1869477383764480/t0(0) o4->lustre-OST0000-osc-ffff95bca0a77800@192.168.203.101@tcp:6/4 lens 4584/448 e 0 to 1 dl 1782872742 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6999.519225] Lustre: lustre-OST0000-osc-ffff95bca0a77800: Connection to lustre-OST0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6999.526909] Lustre: 394574:0:(client.c:2489:ptlrpc_expire_one_request()) Skipped 7 previous similar messages [ 6999.533696] Lustre: Skipped 1 previous similar message [ 6999.540434] Lustre: lustre-OST0000-osc-ffff95bca0a77800: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 7023.565726] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff95bc8545a800.ost_server_uuid 50 [ 7024.124471] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff95bc8545a800.ost_server_uuid in IDLE state after 0 sec [ 7024.627554] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 7025.150145] Lustre: DEBUG MARKER: == sanityn test complete, duration 6910 sec ============== 22:26:07 (1782872767) [ 7025.682690] Lustre: DEBUG MARKER: === sanityn: start cleanup 22:26:08 (1782872768) === [ 7087.309171] Lustre: Unmounted lustre-client [ 7088.389817] Lustre: DEBUG MARKER: === sanityn: finish cleanup 22:27:10 (1782872830) === [ 7088.709344] Lustre: Unmounted lustre-client [ 7124.916724] Key type lgssc unregistered [ 7125.025369] LNet: 403543:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7125.027996] LNetError: 403543:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7125.035055] LNet: Removed LNI 192.168.203.1@tcp [ 7125.289104] Key type .llcrypt unregistered [ 7125.290293] Key type ._llcrypt unregistered