[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 359765789 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003268] x2apic enabled [ 0.004005] Switched APIC routing to physical x2apic. [ 0.005008] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008014] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009004] pid_max: default: 32768 minimum: 301 [ 0.010090] LSM: Security Framework initializing [ 0.011023] Yama: becoming mindful. [ 0.012017] SELinux: Initializing. [ 0.013034] *** VALIDATE selinux *** [ 0.018529] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.022431] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024078] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.025058] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026056] *** VALIDATE tmpfs *** [ 0.027286] *** VALIDATE proc *** [ 0.028156] *** VALIDATE cgroup *** [ 0.028829] *** VALIDATE cgroup2 *** [ 0.029158] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.030087] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.031002] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.032018] Spectre V2 : User space: Vulnerable [ 0.033004] Speculative Store Bypass: Vulnerable [ 0.036227] debug: unmapping init [mem 0xffffffffa2059000-0xffffffffa2060fff] [ 0.038128] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.039347] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.040008] ... version: 2 [ 0.040805] ... bit width: 48 [ 0.041007] ... generic registers: 4 [ 0.041728] ... value mask: 0000ffffffffffff [ 0.042005] ... max period: 00007fffffffffff [ 0.043005] ... fixed-purpose events: 3 [ 0.043798] ... event mask: 000000070000000f [ 0.045085] rcu: Hierarchical SRCU implementation. [ 0.046938] smp: Bringing up secondary CPUs ... [ 0.047359] x86: Booting SMP configuration: [ 0.048009] .... node #0, CPUs: #1 #2 #3 [ 0.050452] smp: Brought up 1 node, 4 CPUs [ 0.051855] smpboot: Max logical packages: 1 [ 0.052006] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.222411] node 0 deferred pages initialised in 169ms [ 0.225107] devtmpfs: initialized [ 0.226182] x86/mm: Memory block size: 128MB [ 0.228710] gcov: version magic: 0x41383552 [ 0.231267] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.234095] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.236321] pinctrl core: initialized pinctrl subsystem [ 0.238128] [ 0.238510] ************************************************************* [ 0.240009] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.241008] ** ** [ 0.243009] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.245008] ** ** [ 0.247008] ** This means that this kernel is built to expose internal ** [ 0.248007] ** IOMMU data structures, which may compromise security on ** [ 0.250008] ** your system. ** [ 0.252008] ** ** [ 0.253006] ** If you see this message and you are not debugging the ** [ 0.255009] ** kernel, report this immediately to your vendor! ** [ 0.257008] ** ** [ 0.258006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.260008] ************************************************************* [ 0.262643] NET: Registered protocol family 16 [ 0.264383] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.266039] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.268047] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.271020] cpuidle: using governor menu [ 0.272459] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.274482] PCI: Using configuration type 1 for base access [ 0.275104] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.283171] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.285024] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.288181] cryptd: max_cpu_qlen set to 1000 [ 0.290720] ACPI: Added _OSI(Module Device) [ 0.292006] ACPI: Added _OSI(Processor Device) [ 0.293009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.294008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.298331] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.303605] ACPI: Interpreter enabled [ 0.305046] ACPI: PM: (supports S0 S3 S4 S5) [ 0.306015] ACPI: Using IOAPIC for interrupt routing [ 0.307077] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.310361] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.320586] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.322022] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.325013] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.328050] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.333256] acpiphp: Slot [2] registered [ 0.334082] acpiphp: Slot [5] registered [ 0.335103] acpiphp: Slot [6] registered [ 0.336089] acpiphp: Slot [3] registered [ 0.338060] acpiphp: Slot [4] registered [ 0.339057] acpiphp: Slot [7] registered [ 0.340074] acpiphp: Slot [8] registered [ 0.341098] acpiphp: Slot [9] registered [ 0.343059] acpiphp: Slot [10] registered [ 0.344056] acpiphp: Slot [11] registered [ 0.345075] acpiphp: Slot [12] registered [ 0.346068] acpiphp: Slot [13] registered [ 0.347072] acpiphp: Slot [14] registered [ 0.349058] acpiphp: Slot [15] registered [ 0.350073] acpiphp: Slot [16] registered [ 0.351062] acpiphp: Slot [17] registered [ 0.352060] acpiphp: Slot [18] registered [ 0.353030] acpiphp: Slot [19] registered [ 0.355113] acpiphp: Slot [20] registered [ 0.356087] acpiphp: Slot [21] registered [ 0.357124] acpiphp: Slot [22] registered [ 0.359068] acpiphp: Slot [23] registered [ 0.360093] acpiphp: Slot [24] registered [ 0.362099] acpiphp: Slot [25] registered [ 0.363085] acpiphp: Slot [26] registered [ 0.363857] acpiphp: Slot [27] registered [ 0.364103] acpiphp: Slot [28] registered [ 0.366075] acpiphp: Slot [29] registered [ 0.367074] acpiphp: Slot [30] registered [ 0.369149] acpiphp: Slot [31] registered [ 0.370068] PCI host bridge to bus 0000:00 [ 0.371013] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.373012] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.375011] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.377013] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.379014] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.381014] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.383173] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.384947] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.388052] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.393920] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.397940] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.400015] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.402014] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.404012] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.406457] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.409621] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.411024] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.413760] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.417010] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.424013] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.428013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.432753] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.436018] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.440011] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.450014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.457490] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.462021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.467017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.477018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.489966] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.491361] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.494341] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.495358] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.497134] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.501099] iommu: Default domain type: Passthrough [ 0.503358] SCSI subsystem initialized [ 0.505072] ACPI: bus type USB registered [ 0.506071] usbcore: registered new interface driver usbfs [ 0.507028] usbcore: registered new interface driver hub [ 0.508043] usbcore: registered new device driver usb [ 0.510111] pps_core: LinuxPPS API ver. 1 registered [ 0.511007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.513051] PTP clock support registered [ 0.515119] EDAC MC: Ver: 3.0.0 [ 0.516474] PCI: Using ACPI for IRQ routing [ 0.517467] NetLabel: Initializing [ 0.519009] NetLabel: domain hash size = 128 [ 0.520005] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.521044] NetLabel: unlabeled traffic allowed by default [ 0.523092] vgaarb: loaded [ 0.524296] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.525010] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.532201] clocksource: Switched to clocksource kvm-clock [ 0.605922] VFS: Disk quotas dquot_6.6.0 [ 0.607295] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.608761] *** VALIDATE ramfs *** [ 0.609839] *** VALIDATE hugetlbfs *** [ 0.611259] pnp: PnP ACPI init [ 0.613529] pnp: PnP ACPI: found 6 devices [ 0.627645] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.629542] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.631279] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.632676] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.634108] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.635643] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.637600] NET: Registered protocol family 2 [ 0.639246] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.642392] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.644883] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.650093] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.652838] TCP: Hash tables configured (established 65536 bind 65536) [ 0.655190] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.657833] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.660277] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.662622] NET: Registered protocol family 1 [ 0.664912] RPC: Registered named UNIX socket transport module. [ 0.666384] RPC: Registered udp transport module. [ 0.667351] RPC: Registered tcp transport module. [ 0.668384] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.669793] NET: Registered protocol family 44 [ 0.671560] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.673585] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.675047] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.676386] PCI: CLS 0 bytes, default 64 [ 0.677423] Unpacking initramfs... [ 1.908876] debug: unmapping init [mem 0xffff9cf33cc64000-0xffff9cf33ffcffff] [ 1.911488] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.912749] software IO TLB: mapped [mem 0x00000000a3000000-0x00000000a7000000] (64MB) [ 1.914440] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.327236] Initialise system trusted keyrings [ 2.328253] Key type blacklist registered [ 2.329340] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.337850] zbud: loaded [ 2.340860] *** VALIDATE nfs *** [ 2.341732] *** VALIDATE nfs4 *** [ 2.342720] pstore: using deflate compression [ 2.344491] Platform Keyring initialized [ 2.405567] NET: Registered protocol family 38 [ 2.407501] Key type asymmetric registered [ 2.408547] Asymmetric key parser 'x509' registered [ 2.410267] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.412055] io scheduler mq-deadline registered [ 2.413322] io scheduler kyber registered [ 2.414488] io scheduler bfq registered [ 2.415554] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.417129] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.418789] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.420364] ACPI: Power Button [PWRF] [ 2.424100] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.446128] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.452753] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.476899] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.502481] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.506474] Non-volatile memory driver v1.3 [ 2.507392] Linux agpgart interface v0.103 [ 2.527774] virtio_blk virtio1: [vda] 134816 512-byte logical blocks (69.0 MB/65.8 MiB) [ 2.530604] vda: detected capacity change from 0 to 69025792 [ 2.544200] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.546119] vdb: detected capacity change from 0 to 1073741824 [ 2.549970] libphy: Fixed MDIO Bus: probed [ 2.552965] usbcore: registered new interface driver usbserial_generic [ 2.554763] usbserial: USB Serial support registered for generic [ 2.556735] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.559798] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.561152] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.563113] mousedev: PS/2 mouse device common for all mice [ 2.565050] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.566322] rtc_cmos 00:05: RTC can wake from S4 [ 2.569273] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.570761] rtc_cmos 00:05: registered as rtc0 [ 2.572592] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.572899] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.574396] intel_pstate: CPU model not supported [ 2.578256] hid: raw HID events driver (C) Jiri Kosina [ 2.579599] usbcore: registered new interface driver usbhid [ 2.581415] usbhid: USB HID core driver [ 2.582641] drop_monitor: Initializing network drop monitor service [ 2.584654] Initializing XFRM netlink socket [ 2.586221] NET: Registered protocol family 10 [ 2.589064] Segment Routing with IPv6 [ 2.590454] NET: Registered protocol family 17 [ 2.592248] mpls_gso: MPLS GSO support [ 2.597466] RAS: Correctable Errors collector initialized. [ 2.598848] AVX version of gcm_enc/dec engaged. [ 2.600141] AES CTR mode by8 optimization enabled [ 2.658759] sched_clock: Marking stable (2658738683, 0)->(3396125274, -737386591) [ 2.660921] registered taskstats version 1 [ 2.663296] Loading compiled-in X.509 certificates [ 2.665230] zswap: loaded using pool lzo/zbud [ 2.686194] Key type big_key registered [ 2.695764] Key type encrypted registered [ 2.696661] ima: No TPM chip found, activating TPM-bypass! [ 2.697777] ima: Allocated hash algorithm: sha1 [ 2.698617] ima: No architecture policies found [ 2.699501] evm: Initialising EVM extended attributes: [ 2.700474] evm: security.selinux [ 2.701096] evm: security.ima [ 2.701613] evm: security.capability [ 2.702358] evm: HMAC attrs: 0x1 [ 2.704770] rtc_cmos 00:05: setting system clock to 2026-04-30 23:50:02 UTC (1777593002) [ 2.709198] debug: unmapping init [mem 0xffffffffa3003000-0xffffffffa31fffff] [ 2.710956] debug: unmapping init [mem 0xffffffffa1d82000-0xffffffffa2058fff] [ 2.718041] Write protecting the kernel read-only data: 28672k [ 2.719701] debug: unmapping init [mem 0xffffffffa0403000-0xffffffffa05fffff] [ 2.721690] debug: unmapping init [mem 0xffffffffa0d14000-0xffffffffa0dfffff] [ 2.747207] 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.755897] systemd[1]: Detected virtualization kvm. [ 2.758052] systemd[1]: Detected architecture x86-64. [ 2.760162] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 2.784051] systemd[1]: No hostname configured. [ 2.785139] systemd[1]: Set hostname to . [ 2.786599] random: systemd: uninitialized urandom read (16 bytes read) [ 2.788320] systemd[1]: Initializing machine ID from random generator. [ 2.839096] random: ln: uninitialized urandom read (6 bytes read) [ 2.918592] random: systemd: uninitialized urandom read (16 bytes read) [ 2.920213] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 2.924032] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 2.929119] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Paths. [ OK ] Reached target Timers. [ OK ] Reached target Sockets. [ OK ] Reached target Swap. Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.466724] device-mapper: uevent: version 1.0.3 [ 3.468089] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.036504] virtio_net virtio0 ens2: renamed from eth0 [ 4.087629] scsi host0: ata_piix [ 4.094996] scsi host1: ata_piix [ 4.108169] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.110981] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 5.432143] random: fast init done [ 7.900601] dracut-initqueue[581]: RTNETLINK answers: File exists [ 9.206912] random: crng init done [ 9.210356] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 9.650977] 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 Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ 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 ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 10.904787] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.155378] SELinux: Disabled at runtime. [ 11.215734] 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.227348] systemd[1]: Detected virtualization kvm. [ 11.229644] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.781230] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.783940] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.786935] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.790447] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.793769] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.802844] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.813658] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. Mounting Kernel Debug File System... [ OK ] Created slice system-getty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Initrd File Systems. Mounting POSIX Message Queue File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Initrd Root 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 ] Started Forward Password Requests to Wall Directory Watch. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-serial\x2dgetty.slice. [ 11.971703] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Remount Root and Kernel File Systems... [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ 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. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.385092] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.676268] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.747258] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.850766] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.875425] EDAC sbridge: Ver: 1.1.2 [ 14.346464] Key type dns_resolver registered [ 14.736898] NFS: Registering the id_resolver key type [ 14.739248] Key type id_resolver registered [ 14.741057] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ 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 oleg224-client login: [ 49.758293] libcfs: loading out-of-tree module taints kernel. [ 49.786092] Key type ._llcrypt registered [ 49.798082] Key type .llcrypt registered [ 49.971872] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 49.976689] alg: No test for adler32 (adler32-zlib) [ 50.974719] Lustre: Lustre: Build Version: 2.17.52_126_gb75093b [ 51.278672] LNet: Added LNI 192.168.202.24@tcp [8/256/0/180] [ 52.887180] Key type lgssc registered [ 53.465554] Lustre: Echo OBD driver; http://www.lustre.org/ [ 112.568844] Lustre: Mounted lustre-client [ 115.011545] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 121.657795] Lustre: DEBUG MARKER: oleg224-client.virtnet: executing check_logdir /tmp/testlogs/ [ 123.141696] Lustre: DEBUG MARKER: oleg224-client.virtnet: executing yml_node [ 124.685769] Lustre: DEBUG MARKER: Client: 2.17.52.126 [ 125.521471] Lustre: DEBUG MARKER: MDS: 2.17.52.126 [ 126.390730] Lustre: DEBUG MARKER: OSS: 2.17.52.126 [ 126.934836] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu Apr 30 19:52:06 EDT 2026 [ 132.709434] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 133.186578] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 133.682465] Lustre: DEBUG MARKER: === sanityn: start setup 19:52:13 (1777593133) === [ 133.844035] Lustre: Mounted lustre-client [ 134.979624] Lustre: DEBUG MARKER: oleg224-client.virtnet: executing check_config_client /mnt/lustre [ 139.231231] Lustre: lustre-OST0000-osc-ffff9cf3830de000: disconnect after 26s idle [ 141.164997] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 145.019374] Lustre: DEBUG MARKER: === sanityn: finish setup 19:52:24 (1777593144) === [ 145.661771] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 19:52:25 (1777593145) [ 148.185664] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 19:52:27 (1777593147) [ 150.189463] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 19:52:29 (1777593149) [ 151.994047] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 19:52:31 (1777593151) [ 153.900884] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 19:52:33 (1777593153) [ 155.771375] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 19:52:35 (1777593155) [ 157.719859] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 19:52:37 (1777593157) [ 159.836916] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 19:52:39 (1777593159) [ 162.421734] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 19:52:41 (1777593161) [ 165.118874] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 19:52:44 (1777593164) [ 167.611540] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 19:52:46 (1777593166) [ 170.962803] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 19:52:50 (1777593170) [ 173.299112] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 19:52:52 (1777593172) [ 175.521223] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 19:52:54 (1777593174) [ 177.821894] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 19:52:57 (1777593177) [ 180.161267] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 19:52:59 (1777593179) [ 182.718698] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 19:53:01 (1777593181) [ 185.282441] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 19:53:04 (1777593184) [ 187.808479] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 19:53:07 (1777593187) [ 190.407473] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 19:53:09 (1777593189) [ 192.779374] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 19:53:12 (1777593192) [ 192.952900] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 331.401652] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 19:55:30 (1777593330) [ 334.020837] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 19:55:33 (1777593333) [ 336.542846] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 19:55:35 (1777593335) [ 338.894506] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 19:55:38 (1777593338) [ 341.255466] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 19:55:40 (1777593340) [ 343.731922] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 19:55:43 (1777593343) [ 344.401876] Lustre: DEBUG MARKER: chmod [ 346.726076] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 19:55:46 (1777593346) [ 806.531291] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 20:03:25 (1777593805) [ 847.859397] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 20:04:07 (1777593847) [ 867.910769] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 20:04:27 (1777593867) [ 903.507993] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 20:05:02 (1777593902) [ 912.968478] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 20:05:12 (1777593912) [ 915.221707] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 20:05:14 (1777593914) [ 915.783542] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 915.825466] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 915.855419] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 915.880422] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 915.905345] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 915.929291] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 915.954704] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 915.979535] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.004930] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.030340] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.054705] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.080332] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.104702] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.130212] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.154432] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.179167] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.206041] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.230754] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.255438] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.278579] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.305200] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.334199] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.360191] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.392730] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.421121] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.449765] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.474552] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.498530] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.520909] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.539620] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.558884] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.580650] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.605736] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.626183] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.650701] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.669526] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.688955] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.710224] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.735042] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.756918] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.781118] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.802859] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.823850] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.845939] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.867059] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.886668] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.905577] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.929686] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.948674] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.967349] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 916.987120] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.011156] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.030818] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.049834] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.068299] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.088176] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.106535] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.127857] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.150174] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.175278] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.198130] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.221768] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.240483] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.264261] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.288566] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.310567] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.332759] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.351409] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.371200] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.390761] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.409813] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.428991] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.449313] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.470264] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.494937] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.516762] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.537869] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.559067] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.579118] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.600299] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.619360] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.643929] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.667779] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.689759] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.712759] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.737457] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.756651] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.775806] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.795057] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.814352] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.834809] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.859395] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.880367] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.898806] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.917557] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.937590] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.960222] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 917.983701] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.007367] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.026582] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.050679] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.075040] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.096867] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.116757] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.135181] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.154781] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.173975] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.193534] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.213581] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.237056] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.258952] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.282035] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.302452] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.321705] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.345430] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.366821] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.386269] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.411395] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.435841] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.461134] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.490233] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.512774] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.537968] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.558363] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.580145] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.600289] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.624511] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.648326] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.668587] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.691450] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.712514] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.733936] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.757526] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.783388] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.808118] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.833768] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.854365] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.877735] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.901265] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.926577] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.947882] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.970089] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 918.989590] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.011271] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.030715] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.055948] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.075584] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.094507] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.117750] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.138290] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.158371] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.181024] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.203581] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.224592] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.247978] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.272951] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.294424] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.314981] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.334524] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.360569] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.382373] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.416528] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.447649] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.470555] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.496821] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.518912] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.539273] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.561241] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.585223] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.606862] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.629631] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.648957] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.674334] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.699763] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.722362] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.748103] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.772782] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.793220] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.815555] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.835756] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.855907] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.876580] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.896637] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.918738] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.938503] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.956986] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 919.978907] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.000279] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.023133] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.051695] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.074746] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.098055] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.124524] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.150334] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.169109] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.188804] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.210145] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.228599] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.248637] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.272784] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.298617] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.321971] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.347448] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.373667] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.397077] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.422877] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.445270] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.466782] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.489397] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.512524] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.536423] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.566150] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.593209] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.619422] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.649058] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.677661] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.706070] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.731696] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.756570] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.783097] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.808516] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.837481] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.868715] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.892036] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.910607] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.929605] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.947731] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.967489] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 920.991567] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.012486] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.032159] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.054117] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.074573] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.094408] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.115546] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.136684] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.159440] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.179082] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.199335] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.219282] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.243478] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.265702] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.289131] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.311142] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.331030] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.349135] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.367698] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.387080] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.405622] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.426037] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.448709] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.470736] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.490222] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.512393] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.531651] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.552915] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.573593] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.594712] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.614789] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.632269] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.649679] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.667050] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.686216] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.703625] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.721372] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.740095] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.758353] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.777280] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.796877] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.814770] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.832748] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.850918] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.870788] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.890569] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.910593] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.932504] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.956111] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 921.979396] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.003936] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.031144] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.056922] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.076129] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.096066] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.117134] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.138320] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.158690] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.177828] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.198773] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.219342] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.239595] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.261451] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.282293] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.302886] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.322915] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.343190] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.364488] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.386051] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.408971] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.429933] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.448735] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.469232] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.488110] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.509880] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.528701] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.548528] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.566777] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.586204] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.606582] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.626355] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.645034] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.665129] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.686519] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.706271] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.724413] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.743468] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.762225] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.781498] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.800157] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.819168] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.838579] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.858598] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.877848] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.899566] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.920743] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.939532] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.959735] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 922.979548] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.001822] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.025033] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.045678] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.066067] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.086057] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.105465] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.124534] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.143092] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.160844] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.182502] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.201417] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.219466] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.239832] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.260629] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.280965] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.302294] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.322453] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.342947] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.364385] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.383565] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.402126] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.421075] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.439189] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.457934] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.476196] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.495401] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.516815] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.539309] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.558162] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.583908] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.606477] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.623991] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.642188] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.661285] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.683662] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.703169] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.722946] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.744312] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.764377] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.785831] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.806296] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.826172] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.847025] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.874611] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.900681] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.926865] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.954375] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 923.974275] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.003645] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.029193] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.056216] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.082491] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.102595] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.122052] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.146291] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.169722] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.188690] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.208145] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.225487] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.243785] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.262950] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.283068] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.302385] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.321610] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.344173] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.364888] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.384392] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.406475] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.428566] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.449449] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.467404] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.486321] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.504585] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.524473] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.544789] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.565245] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.583216] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.604526] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.624598] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.642367] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.660785] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.678917] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.697828] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.718499] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.739187] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.760621] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.778664] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.798166] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.815581] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.838241] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.858740] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.881154] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.899284] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.918058] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.937466] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.958854] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.976574] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 924.995749] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.016149] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.035885] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.053716] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.072586] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.090646] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.109279] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.128325] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.149204] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.170613] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.194806] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.214354] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.233853] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.252318] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.271693] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.291543] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.312567] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.331058] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.351471] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.370278] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.388994] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.407568] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.428140] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.451839] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.473749] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.497538] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.517678] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.538804] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.557036] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.574799] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.593352] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.614616] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.634019] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.653296] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.673553] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.694300] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.717101] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.734912] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.752696] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.771915] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.791713] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.811173] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.830864] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.850204] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.871314] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.893032] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.915301] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.934924] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.955227] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.975533] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 925.995325] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.017418] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.039274] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.059109] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.078349] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.095455] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.114622] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.139380] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.163571] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.184277] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.203526] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.220966] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.238695] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.256974] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.276707] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.296024] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.314807] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.335217] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.355378] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.376111] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.398189] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.422355] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.441509] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.461245] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.482951] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.503729] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.524502] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.544685] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.563159] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.582191] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.601178] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.620476] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.640285] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.659597] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.677953] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.695249] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.714169] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.733083] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.757179] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.781270] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.801724] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.819629] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.838220] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.858129] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.880137] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.903926] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.925316] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.945104] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.966638] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 926.987665] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.007698] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.032986] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.055322] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.077738] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.101638] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.130134] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.151321] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.170575] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.190416] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.211181] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.233537] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.262304] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.282518] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.303082] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.324752] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.343820] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.367533] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.389889] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.411607] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.433580] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.454631] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.475502] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.499655] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.524402] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.547374] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.572520] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.598648] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.626215] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.647075] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.670270] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.697113] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.719304] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.743553] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.762956] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.783246] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.805347] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.827446] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.848100] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.870105] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.895621] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.919300] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.941873] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.962206] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 927.981456] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.003974] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.024993] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.044413] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.064155] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.083250] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.103208] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.124132] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.145093] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.167043] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.187585] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.210442] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.234579] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.253614] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.272762] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.293675] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.313681] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.332920] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.352635] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.371298] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.391320] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.411719] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.430352] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.452269] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.472900] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.492657] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.513848] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.531377] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.548255] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.567321] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.585759] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.604854] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.623689] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.642262] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.660482] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.679410] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.697746] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.715785] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.735603] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.755502] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.776523] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.798115] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.817218] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.836057] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.856026] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.874925] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.896023] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.915647] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.935855] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.955432] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.975531] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 928.995466] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.015943] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.041084] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.061752] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.083106] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.102553] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.124366] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.145226] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.165522] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.184865] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.205438] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.224585] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.244684] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.265474] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.285685] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.312246] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.334214] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.353034] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.373311] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.395160] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.412947] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.435556] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.457764] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.482223] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.504782] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.524163] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.544029] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.563382] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.581524] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.602709] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.624165] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.647823] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.670804] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.692504] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.712246] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.731129] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.750229] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.768874] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.788797] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.808659] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.829692] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.850342] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.869805] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.891606] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.911022] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.929588] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.952209] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.975314] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 929.995818] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.015059] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.033927] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.055117] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.075425] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.096806] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.116244] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.135160] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.154227] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.172868] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.191248] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.208510] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.228108] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.246346] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.265980] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.286119] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.305410] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.324510] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.346776] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.368845] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.389067] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.408800] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.431731] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.450574] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.469400] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.487247] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.506716] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.524794] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.543470] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.562184] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.580173] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.598867] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.618343] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.642190] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.662512] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.682415] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.703666] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.728303] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.747962] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.767195] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.788238] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.810073] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.831302] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.849709] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.872923] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.895369] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.916439] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.939442] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.963653] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 930.987509] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.009380] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.030520] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.053874] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.075494] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.096968] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.116010] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.135808] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.155482] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.176942] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.199255] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.218611] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.241957] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.260767] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.279450] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.297912] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.317268] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.338530] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.358779] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.379499] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.402121] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.424363] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.444790] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.468525] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.487417] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.507474] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.526906] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.547097] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.567747] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.589072] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.608499] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.628838] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.648507] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.668821] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.688940] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.707987] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.727696] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.745857] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.768550] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.791954] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.816263] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.835638] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.859464] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.880847] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.898929] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.920753] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.941751] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.962852] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 931.984641] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.007853] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.037411] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.062154] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.088730] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.116168] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.140618] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.165790] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.191897] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.220078] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.245944] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.267920] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.288530] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.312074] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.332030] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.352828] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.376385] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.404522] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.428540] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.454172] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.478983] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.503413] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.529515] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.556235] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.581786] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.605176] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.628805] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.653283] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.673404] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.692974] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.711455] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.732828] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.753173] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.775862] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.799874] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.820903] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.841107] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.859686] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.879582] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.902627] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.922292] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.941129] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.960535] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 932.980202] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.000221] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.021685] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.045560] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.065381] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.084336] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.106326] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.125521] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.145437] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.168187] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.189698] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.209496] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.231563] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.250394] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.271864] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.291780] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.312899] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.333693] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.353018] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.373722] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.392709] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.412490] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.432102] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.453085] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.475115] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.498762] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.522065] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.542263] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.560830] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.579974] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.599281] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.618231] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.636605] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.659883] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.679684] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.699698] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.720275] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.744357] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.763852] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.784616] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.803676] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.821507] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.841407] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.862500] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.882242] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.901701] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.919348] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.939479] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.958635] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.978819] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 933.998022] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.017170] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.039209] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.060763] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.082880] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.105090] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.126204] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.147759] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.170278] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.192403] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.214623] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.238158] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.261894] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.283721] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.306385] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.326563] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.349137] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.371035] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.394495] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.423318] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.450522] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.475858] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.497725] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.518661] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.536471] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.558504] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.581151] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.606827] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.628692] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.648571] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.671090] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.691854] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.710717] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.730531] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.750590] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.769269] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.790851] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.811934] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.833662] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.856996] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.879865] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.900200] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.920956] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.943912] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.965079] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 934.984524] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.008041] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.027911] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.047171] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.066594] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.087654] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.110557] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.135652] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.160185] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.182771] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.205053] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.229771] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.251859] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 935.270591] rw_seq_cst_vs_d (32518): drop_caches: 3 [ 937.628995] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 20:05:36 (1777593936) [ 937.734138] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 937.798875] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 937.814410] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 937.832783] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 937.850455] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 937.868022] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 937.887762] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 937.909869] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 937.927195] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 937.945236] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 937.983512] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.002167] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.026624] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.044091] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.065586] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.084195] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.100125] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.121362] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.138886] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.154749] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.169862] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.189867] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.207147] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.224926] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.280017] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.318577] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.341396] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.359160] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.378560] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.433495] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.452289] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.467691] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.485933] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.502266] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.517750] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.539878] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.562246] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.581647] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.596947] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.616831] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.636529] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.651092] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.666622] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.682898] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.776028] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.804252] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.829765] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.850032] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.973805] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 938.995659] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.054714] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.154586] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.176446] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.200567] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.217717] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.234950] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.250991] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.270330] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.292265] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.316067] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.388621] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.449957] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.472719] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.495984] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.595373] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.638167] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.660709] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.689954] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.726716] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.752604] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.775355] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.804124] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.917865] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.936941] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.970680] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 939.988840] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.080293] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.095524] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.111172] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.125889] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.143744] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.162168] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.205442] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.225785] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.245735] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.290170] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.334936] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.352223] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.368916] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.388609] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.409630] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.426483] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.445042] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.463031] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.482665] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.496180] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.510391] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.526317] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.541923] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.558958] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.572977] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.586578] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.602287] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.616650] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.634923] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.651709] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.672922] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.694394] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.710866] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.728650] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.744080] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.761424] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.793395] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.812128] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.827760] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.842238] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.859621] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.876517] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.894615] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.913021] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.932493] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.961051] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 940.980522] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.005188] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.023188] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.040383] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.062153] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.081417] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.110719] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.136315] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.160578] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.176064] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.190497] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.247772] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.269881] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.296662] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.317373] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.334651] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.353490] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.371803] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.392052] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.420109] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.441359] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.459728] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.479737] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.498074] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.515087] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.529445] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.545714] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.585841] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.608611] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.650138] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.665494] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.680880] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.696196] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.714558] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.727234] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.742944] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.761343] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.778083] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.790468] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.805991] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.819386] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.839606] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.857478] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.874055] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.891741] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.905755] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.917212] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.932792] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.946853] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.959859] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.974190] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 941.989091] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.006726] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.029332] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.042328] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.055232] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.069967] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.083241] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.097394] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.111944] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.126331] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.175202] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.194469] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.209655] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.223674] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.236838] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.271132] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.290463] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.305381] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.318184] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.331480] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.343882] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.356540] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.391329] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.406418] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.421502] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.440526] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.474159] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.487172] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.501582] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.514491] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.528446] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.542026] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.573063] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.587592] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.601899] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.619097] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.632190] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.645421] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.657996] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.695926] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.733122] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.764465] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.781639] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.799508] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.814898] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.829741] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.843291] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.856624] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.871927] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.887452] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.905312] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.925364] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.941884] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.963057] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.980502] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 942.995059] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.010800] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.025710] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.059681] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.072828] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.109651] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.167054] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.180905] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.213567] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.231711] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.247707] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.261013] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.274811] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.307883] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.326415] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.360348] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.390223] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.404843] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.418890] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.436900] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.469964] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.509590] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.534882] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.550940] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.583140] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.596206] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.611988] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.627445] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.640379] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.654825] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.667543] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.681717] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.700047] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.717670] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.731750] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.744067] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.756899] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.773857] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.791770] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.831886] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.850804] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.866885] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.882568] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.899715] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.918303] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.942084] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 943.963939] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.061971] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.105639] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.127764] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.149149] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.170752] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.194272] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.212046] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.227295] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.245710] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.261357] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.276469] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.289961] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.320923] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.337787] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.371501] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.388843] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.405851] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.428371] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.454465] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.469368] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.481886] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.494799] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.509216] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.521850] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.537592] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.571222] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.591853] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.627505] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.641046] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.655506] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.673183] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.692312] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.712901] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.734580] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.764772] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.780706] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.793414] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.806212] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.823758] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.837820] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.856064] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.868378] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.882599] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.920238] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.945206] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.970507] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 944.992457] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.012946] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.025924] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.046727] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.081170] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.095330] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.110043] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.128303] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.143035] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.199589] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.217927] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.235552] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.270035] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.285534] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.301430] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.345166] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.368807] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.387951] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.400836] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.439050] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.458344] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.473316] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.488048] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.506929] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.524570] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.539369] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.551888] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.566741] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.581025] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.594246] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.606806] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.621322] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.655811] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.673193] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.685957] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.698876] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.713151] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.726357] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.745572] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.764091] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.782564] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.796478] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.813450] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.832022] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.848132] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.882725] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.904499] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.940270] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 945.976506] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.010440] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.024836] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.039686] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.053804] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.068243] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.091145] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.106848] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.122778] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.138704] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.161760] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.262061] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.283367] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.301588] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.319644] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.338076] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.358751] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.400982] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.417948] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.431877] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.447614] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.465843] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.484842] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.506378] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.548828] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.566151] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.581248] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.600416] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.622616] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.644770] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.661952] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.682849] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.702662] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.720608] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.801764] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.817191] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.834796] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.852651] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.872362] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.886947] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.926724] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.949476] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.972693] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 946.997303] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.020928] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.039662] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.063664] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.088781] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.108814] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.168666] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.185383] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.203932] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.222747] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.244640] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.457200] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.474612] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.492553] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.509278] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.526658] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.549406] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.612474] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.639698] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.668644] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.694352] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.742639] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 947.766835] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.007879] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.026471] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.081616] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.129199] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.147354] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.164895] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.201325] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.228802] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.250136] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.269327] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.293849] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.337541] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.480566] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.500915] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.577609] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.598949] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.635242] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.656115] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.677046] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.696299] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.718939] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.754369] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.795616] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.809129] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.830294] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.866540] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.933597] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.957897] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 948.994743] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.032703] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.046417] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.084667] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.288892] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.304159] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.340734] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.356521] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.379173] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.399971] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.421551] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.554734] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.592562] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.610896] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.633931] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.653951] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.748965] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.771521] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.817509] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.841772] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.863073] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 949.924934] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.050798] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.068553] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.112505] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.137745] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.151727] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.255156] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.276856] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.294226] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.313224] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.355250] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.375719] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.482691] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.506310] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.522350] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.551115] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.573283] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.621283] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.641276] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.693974] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.709236] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.722662] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.739994] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.773436] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.833997] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.854622] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.869535] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.883457] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.898654] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.923495] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.940735] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 950.980768] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.000326] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.020515] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.078606] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.159099] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.176787] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.191755] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.206620] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.245072] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.263848] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.282450] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.355718] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.381966] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.425630] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.443725] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.486944] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.505598] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.523692] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.555611] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.573942] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.638937] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.658128] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.685163] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.705961] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.724623] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.744506] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.764500] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.784070] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.803868] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.824517] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.863582] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.879960] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.913867] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.933245] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.958224] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 951.981051] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.000429] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.023454] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.038529] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.051893] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.070143] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.087084] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.128193] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.207481] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.257119] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.281184] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.340718] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.400738] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.423623] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.445317] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.464663] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.489373] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.508979] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.593305] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.619580] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.640790] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.675374] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.695489] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.787149] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.813776] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.840036] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.877482] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 952.974141] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.053592] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.092754] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.123335] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.167858] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.189148] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.297206] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.315560] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.330787] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.345252] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.362267] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.376662] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.397420] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.411230] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.425202] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.442290] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.458148] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.489519] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.505886] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.524953] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.542080] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.560996] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.576534] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.590987] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.609691] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.627248] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.677509] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.711248] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.724780] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.761913] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.775710] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.792456] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.812408] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.832411] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.851854] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.869742] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.886293] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.904273] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.921861] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.956927] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.970561] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 953.984171] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.002390] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.020163] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.039129] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.056453] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.120790] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.150068] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.166878] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.195747] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.214082] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.233169] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.247215] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.261744] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.291095] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.304725] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.319235] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.334365] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.353194] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.372185] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.389849] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.459878] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.479472] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.494584] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.512092] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.530087] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.543533] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.561506] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.579767] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.595550] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.612485] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.635967] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.653945] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.783909] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.829117] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.938430] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.971775] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 954.990785] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.006848] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.040365] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.053734] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.074797] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.089171] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.106964] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.127081] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.187837] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.202182] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.221442] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.242893] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.308026] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.339324] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.352114] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.383365] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.401757] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.420704] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.444086] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.464727] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.486543] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.507089] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.586595] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.606746] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.626560] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.639896] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.654881] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.669919] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.752101] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.770759] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.783703] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.800872] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.814249] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.829112] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.881905] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.901795] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.917146] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.933934] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.957421] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 955.992703] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.009330] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.055212] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.071987] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.094000] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.115652] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.140531] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.164520] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.206434] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.226960] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.245810] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.277510] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.297847] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.315049] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.348514] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.367574] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.387421] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.408033] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.465729] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.500338] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.531650] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.554470] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.576583] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.596949] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.634679] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.660755] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.677878] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.721988] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.737194] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.755143] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.815014] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.846389] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.864130] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.880818] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.901702] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.916807] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.936526] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 956.995346] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.138027] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.155765] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.171404] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.192053] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.211776] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.230509] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.248857] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.274172] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.316567] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.332938] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.351483] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.393620] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.414350] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.429661] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.446316] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.459276] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.532908] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.549807] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.565466] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.628507] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.646562] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 957.663454] rw_seq_cst_vs_d (33104): drop_caches: 3 [ 958.432170] Lustre: lustre-OST0001-osc-ffff9cf3830de000: disconnect after 23s idle [ 958.436846] Lustre: Skipped 1 previous similar message [ 959.901887] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 20:05:59 (1777593959) [ 961.908601] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 20:06:01 (1777593961) [ 963.930767] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 20:06:03 (1777593963) [ 971.432325] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 20:06:10 (1777593970) [ 971.574970] bash (35586): drop_caches: 3 [ 974.685446] bash (35586): drop_caches: 3 [ 977.793068] bash (35586): drop_caches: 3 [ 981.326722] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 20:06:20 (1777593980) [ 986.026195] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 20:06:25 (1777593985) [ 997.582199] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 20:06:36 (1777593996) [ 1000.149064] Lustre: DEBUG MARKER: loop 5 [ 1001.628017] Lustre: DEBUG MARKER: loop 10 [ 1003.213336] Lustre: DEBUG MARKER: loop 15 [ 1004.818979] Lustre: DEBUG MARKER: loop 20 [ 1007.223942] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 20:06:46 (1777594006) [ 1009.275034] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 20:06:48 (1777594008) [ 1011.263586] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 20:06:50 (1777594010) [ 1030.111233] Lustre: lustre-OST0000-osc-ffff9cf3830de000: disconnect after 22s idle [ 1030.113334] Lustre: Skipped 1 previous similar message [ 1035.231198] Lustre: lustre-OST0001-osc-ffff9cf3830de000: disconnect after 24s idle [ 1035.233585] Lustre: Skipped 2 previous similar messages [ 1074.820877] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 20:07:54 (1777594074) [ 1076.893207] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 20:07:56 (1777594076) [ 1078.918429] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 20:07:58 (1777594078) [ 1081.106435] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 20:08:00 (1777594080) [ 1083.246053] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 20:08:02 (1777594082) [ 1086.113758] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 20:08:05 (1777594085) [ 1089.940379] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 20:08:09 (1777594089) [ 1093.077790] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1093.633370] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 20:08:12 (1777594092) [ 1098.002557] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 20:08:17 (1777594097) [ 1098.117030] Lustre: *** cfs_fail_loc=314, val=0*** [ 1099.167221] Lustre: *** cfs_fail_loc=314, val=0*** [ 1099.168633] Lustre: Skipped 2 previous similar messages [ 1101.259475] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 20:08:20 (1777594100) [ 1106.480290] Lustre: *** cfs_fail_loc=314, val=0*** [ 1106.481558] Lustre: Skipped 1 previous similar message [ 1106.499308] LustreError: lustre-OST0000-osc-ffff9cf383013000: operation ldlm_enqueue to node 192.168.202.124@tcp failed: rc = -107 [ 1106.501584] Lustre: lustre-OST0000-osc-ffff9cf383013000: Connection to lustre-OST0000 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1106.508928] LustreError: lustre-OST0000-osc-ffff9cf383013000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1106.516050] LustreError: 46566:0:(ldlm_resource.c:1180:ldlm_resource_complain()) lustre-OST0000-osc-ffff9cf383013000: namespace resource [0x280000401:0x39:0x0].0x0 (ffff9cf38972d200) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1106.520884] Lustre: lustre-OST0000-osc-ffff9cf383013000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 1109.030696] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 20:08:28 (1777594108) [ 1109.090306] LustreError: 47154:0:(file.c:818:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1112.111115] LustreError: 47154:0:(file.c:818:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1114.376308] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 20:08:33 (1777594113) [ 1117.117691] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 20:08:36 (1777594116) [ 1120.218506] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1120.771088] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 20:08:40 (1777594120) [ 1121.329263] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1121.867159] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 20:08:41 (1777594121) [ 1127.311109] LustreError: lustre-MDT0000-mdc-ffff9cf3830de000: operation ldlm_enqueue to node 192.168.202.124@tcp failed: rc = -107 [ 1127.313285] Lustre: lustre-MDT0000-mdc-ffff9cf3830de000: Connection to lustre-MDT0000 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1127.392389] LustreError: MGC192.168.202.124@tcp: Connection to MGS (at 192.168.202.124@tcp) was lost; in progress operations using this service will fail [ 1127.396843] Lustre: Evicted from MGS (at 192.168.202.124@tcp) after server handle changed from 0x920f3b91e0dd933e to 0x920f3b91e1009988 [ 1127.400845] Lustre: MGC192.168.202.124@tcp: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 1130.889556] Lustre: lustre-MDT0000-mdc-ffff9cf383013000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 1141.573882] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 20:09:00 (1777594140) [ 1160.205696] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 20:09:19 (1777594159) [ 1165.287540] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 20:09:24 (1777594164) [ 1214.433030] Lustre: lustre-OST0001-osc-ffff9cf3830de000: Connection to lustre-OST0001 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1214.438837] Lustre: Skipped 1 previous similar message [ 1214.441471] LustreError: lustre-OST0001-osc-ffff9cf383013000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1214.445621] LustreError: lustre-OST0001-osc-ffff9cf3830de000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1214.445996] Lustre: lustre-OST0001-osc-ffff9cf383013000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 1214.451728] Lustre: Skipped 2 previous similar messages [ 1228.727089] Lustre: lustre-OST0000-osc-ffff9cf3830de000: Connection to lustre-OST0000 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1228.731417] Lustre: Skipped 1 previous similar message [ 1228.734307] LustreError: lustre-OST0000-osc-ffff9cf3830de000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1228.738247] Lustre: lustre-OST0000-osc-ffff9cf3830de000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 1234.911181] Lustre: lustre-OST0001-osc-ffff9cf3830de000: disconnect after 20s idle [ 1240.597726] Lustre: DEBUG MARKER: oleg224-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cf383013000.ost_server_uuid 50 [ 1241.118444] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cf383013000.ost_server_uuid in FULL state after 0 sec [ 1242.352562] Lustre: DEBUG MARKER: oleg224-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cf383013000.ost_server_uuid 50 [ 1242.806532] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cf383013000.ost_server_uuid in IDLE state after 0 sec [ 1244.374200] Lustre: DEBUG MARKER: oleg224-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cf383013000.ost_server_uuid 50 [ 1244.851072] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cf383013000.ost_server_uuid in FULL state after 0 sec [ 1245.976972] Lustre: DEBUG MARKER: oleg224-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cf383013000.ost_server_uuid 50 [ 1246.445302] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cf383013000.ost_server_uuid in IDLE state after 0 sec [ 1249.391743] Lustre: DEBUG MARKER: oleg224-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cf383013000.ost_server_uuid 50 [ 1249.864824] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cf383013000.ost_server_uuid in FULL state after 0 sec [ 1250.271189] Lustre: lustre-OST0000-osc-ffff9cf3830de000: disconnect after 22s idle [ 1250.274386] Lustre: Skipped 1 previous similar message [ 1250.998288] Lustre: DEBUG MARKER: oleg224-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cf383013000.ost_server_uuid 50 [ 1251.483548] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cf383013000.ost_server_uuid in IDLE state after 0 sec [ 1251.977613] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 20:10:51 (1777594251) [ 1252.747693] Lustre: DEBUG MARKER: Race attempt 0 [ 1254.271532] Lustre: DEBUG MARKER: Wait for 58470 58605 for 60 sec... [ 1316.440456] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 20:11:55 (1777594315) [ 1321.563954] Lustre: DEBUG MARKER: start test - cycle (0) [ 1337.524200] Lustre: DEBUG MARKER: start test - cycle (1) [ 1352.366572] Lustre: DEBUG MARKER: start test - cycle (2) [ 1368.439719] Lustre: DEBUG MARKER: start test - cycle (3) [ 1382.815390] Lustre: DEBUG MARKER: start test - cycle (4) [ 1398.613981] Lustre: DEBUG MARKER: start test - cycle (5) [ 1413.684088] Lustre: DEBUG MARKER: start test - cycle (6) [ 1429.591959] Lustre: DEBUG MARKER: start test - cycle (7) [ 1445.245921] Lustre: DEBUG MARKER: start test - cycle (8) [ 1459.815149] Lustre: DEBUG MARKER: start test - cycle (9) [ 1476.039255] Lustre: DEBUG MARKER: start test - cycle (10) [ 1492.630777] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 20:14:51 (1777594491) [ 1501.151193] Lustre: lustre-OST0001-osc-ffff9cf383013000: disconnect after 21s idle [ 1505.996821] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 20:15:05 (1777594505) [ 1508.170831] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 20:15:07 (1777594507) [ 1511.259874] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 20:15:10 (1777594510) [ 1514.378417] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 20:15:13 (1777594513) [ 1514.459645] Lustre: *** cfs_fail_loc=411, val=0*** [ 1516.391190] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1516.884863] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 20:15:16 (1777594516) [ 1524.918155] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 20:15:24 (1777594524) [ 1532.601923] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 20:15:31 (1777594531) [ 1540.268171] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 20:15:39 (1777594539) [ 1547.231145] Lustre: lustre-OST0001-osc-ffff9cf3830de000: disconnect after 25s idle [ 1547.370487] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 20:15:46 (1777594546) [ 1552.080122] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 20:15:51 (1777594551) [ 1556.852664] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 20:15:56 (1777594556) [ 1561.795119] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 20:16:01 (1777594561) [ 1566.661014] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 20:16:06 (1777594566) [ 1571.464210] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 20:16:10 (1777594570) [ 1576.278338] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 20:16:15 (1777594575) [ 1581.329824] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 20:16:20 (1777594580) [ 1586.472953] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 20:16:25 (1777594585) [ 1603.551158] Lustre: lustre-OST0000-osc-ffff9cf3830de000: disconnect after 24s idle [ 1603.553312] Lustre: Skipped 1 previous similar message [ 2207.711246] Lustre: lustre-OST0000-osc-ffff9cf383013000: disconnect after 22s idle [ 2207.720724] Lustre: Skipped 1 previous similar message [ 2216.377010] hrtimer: interrupt took 9271444 ns [ 2305.972609] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 20:28:25 (1777595305) [ 2311.706146] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 20:28:30 (1777595310) [ 2317.904805] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 20:28:37 (1777595317) [ 2324.548352] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 20:28:43 (1777595323) [ 2330.426410] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 20:28:49 (1777595329) [ 2336.560240] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 20:28:55 (1777595335) [ 2342.121874] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 20:29:01 (1777595341) [ 2347.545190] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 20:29:06 (1777595346) [ 2351.071189] Lustre: lustre-OST0000-osc-ffff9cf3830de000: disconnect after 24s idle [ 2351.074226] Lustre: Skipped 1 previous similar message [ 2353.120956] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 20:29:12 (1777595352) [ 2380.842462] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 20:29:40 (1777595380) [ 2385.813573] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 20:29:45 (1777595385) [ 2390.963077] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 20:29:50 (1777595390) [ 2396.129839] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 20:29:55 (1777595395) [ 2401.141495] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 20:30:00 (1777595400) [ 2406.240984] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 20:30:05 (1777595405) [ 2411.158695] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 20:30:10 (1777595410) [ 2416.272311] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 20:30:15 (1777595415) [ 2421.333189] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 20:30:20 (1777595420) [ 2459.097270] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 20:30:58 (1777595458) [ 2895.930696] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 20:38:15 (1777595895) [ 2901.375806] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 20:38:20 (1777595900) [ 2906.765831] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 20:38:26 (1777595906) [ 2912.422816] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 20:38:31 (1777595911) [ 2917.757311] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 20:38:37 (1777595917) [ 2922.880207] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 20:38:42 (1777595922) [ 2927.898500] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 20:38:47 (1777595927) [ 2933.136439] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 20:38:52 (1777595932) [ 2938.079818] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 20:38:57 (1777595937) [ 2943.389398] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 20:39:02 (1777595942) [ 2960.351159] Lustre: lustre-OST0001-osc-ffff9cf3830de000: disconnect after 20s idle [ 2960.354546] Lustre: Skipped 3 previous similar messages [ 2983.713344] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 20:39:43 (1777595983) [ 2989.135963] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 20:39:48 (1777595988) [ 2994.603753] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 20:39:53 (1777595993) [ 3000.320566] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 20:39:59 (1777595999) [ 3005.976986] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 20:40:05 (1777596005) [ 3011.634530] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 20:40:10 (1777596010) [ 3016.671413] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 20:40:16 (1777596016) [ 3021.883046] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 20:40:21 (1777596021) [ 3027.487679] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 20:40:26 (1777596026) [ 3591.460048] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 20:49:50 (1777596590) [ 3597.044067] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 20:49:56 (1777596596) [ 3601.985291] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 20:50:01 (1777596601) [ 3606.958982] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 20:50:06 (1777596606) [ 3612.190978] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 20:50:11 (1777596611) [ 3617.314386] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 20:50:16 (1777596616) [ 3623.119946] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 20:50:22 (1777596622) [ 3628.089295] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 20:50:27 (1777596627) [ 3632.951968] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 20:50:32 (1777596632) [ 3637.811884] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 20:50:37 (1777596637) [ 3642.563208] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 20:50:41 (1777596641) [ 3648.691811] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 20:50:48 (1777596648) [ 3654.423983] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 20:50:53 (1777596653) [ 3660.492131] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 20:50:59 (1777596659) [ 3667.285894] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 20:51:06 (1777596666) [ 3672.708236] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 20:51:12 (1777596672) [ 3680.348492] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 20:51:19 (1777596679) [ 3680.429725] LustreError: 6147:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3682.511124] LustreError: 6147:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 3687.819756] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 20:51:27 (1777596687) [ 3692.238930] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 20:51:31 (1777596691) [ 3692.320681] LustreError: 240368:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3696.383109] LustreError: 240368:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3696.390722] LustreError: 240368:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3700.455091] LustreError: 240368:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3700.468734] LustreError: 240374:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3704.527182] LustreError: 240374:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3707.293469] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 20:51:46 (1777596706) [ 3714.311610] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 20:51:53 (1777596713) [ 3718.440724] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 20:51:57 (1777596717) [ 3722.907004] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 20:52:02 (1777596722) [ 3728.351250] Lustre: lustre-OST0001-osc-ffff9cf3830de000: disconnect after 21s idle [ 3728.355840] Lustre: Skipped 2 previous similar messages [ 3748.576824] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 20:52:27 (1777596747) [ 3756.935187] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 20:52:36 (1777596756) [ 3765.843467] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 20:52:44 (1777596764) [ 3779.876994] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 20:52:59 (1777596779) [ 3790.557389] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 20:53:09 (1777596789) [ 3804.613643] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 20:53:23 (1777596803) [ 3819.150862] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 20:53:38 (1777596818) [ 3834.553606] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 20:53:53 (1777596833) [ 3843.550253] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 20:54:02 (1777596842) [ 3872.246481] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 20:54:31 (1777596871) [ 3874.410221] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3876.724628] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 20:54:36 (1777596876) [ 3879.346569] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 20:54:38 (1777596878) [ 3881.464528] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 20:54:40 (1777596880) [ 3883.441022] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 20:54:42 (1777596882) [ 3897.176071] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 20:54:56 (1777596896) [ 3914.529325] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 20:55:13 (1777596913) [ 3917.566797] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 20:55:16 (1777596916) [ 3920.725379] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 20:55:19 (1777596919) [ 3923.850641] LustreError: lustre-MDT0000-mdc-ffff9cf3830de000: operation ldlm_enqueue to node 192.168.202.124@tcp failed: rc = -35 [ 3927.855264] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 20:55:27 (1777596927) [ 3928.099712] LustreError: 2403:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3930.183163] LustreError: 2403:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 3935.691122] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 20:55:34 (1777596934) [ 3987.476539] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 20:56:26 (1777596986) [ 3990.784593] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 20:56:29 (1777596989) [ 3996.950487] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 20:56:36 (1777596996) [ 4004.056801] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 20:56:43 (1777597003) [ 4010.406157] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 20:56:49 (1777597009) [ 4020.088200] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 20:56:59 (1777597019) [ 4030.429137] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 20:57:09 (1777597029) [ 4035.777425] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 20:57:14 (1777597034) [ 4041.674844] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 20:57:20 (1777597040) [ 4052.705165] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 20:57:31 (1777597051) [ 4098.503400] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 20:58:17 (1777597097) [ 4217.011941] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 21:00:16 (1777597216) [ 4334.046228] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 21:02:13 (1777597333) [ 4363.231387] Lustre: lustre-OST0001-osc-ffff9cf3830de000: disconnect after 23s idle [ 4363.234668] Lustre: Skipped 9 previous similar messages [ 4606.927571] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 21:06:46 (1777597606) [ 4637.696834] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 21:07:16 (1777597636) [ 4666.467698] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 21:07:45 (1777597665) [ 4693.755682] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 21:08:13 (1777597693) [ 4755.271728] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 21:09:14 (1777597754) [ 4811.859425] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 21:10:11 (1777597811) [ 4904.486681] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 21:11:43 (1777597903) [ 4905.978359] LustreError: 287228:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9cf3830de000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4906.001240] Lustre: Unmounted lustre-client [ 4907.010368] LustreError: 287241:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9cf383013000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4907.014834] LustreError: 287241:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 4907.048224] Lustre: Unmounted lustre-client [ 4971.434634] Lustre: Mounted lustre-client [ 4972.998255] Lustre: Mounted lustre-client [ 4974.321730] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4993.504316] Lustre: lustre-OST0000-osc-ffff9cf389273000: disconnect after 21s idle [ 4993.510390] Lustre: Skipped 10 previous similar messages [ 5032.382705] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 21:13:51 (1777598031) [ 5039.588196] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 21:13:58 (1777598038) [ 5042.522364] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 21:14:01 (1777598041) [ 5093.511591] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 21:14:52 (1777598092) [ 5144.203648] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 21:15:43 (1777598143) [ 5150.196121] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 21:15:49 (1777598149) [ 5188.508610] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 21:16:27 (1777598187) [ 5198.753773] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 21:16:38 (1777598198) [ 5238.450595] Lustre: DEBUG MARKER: == sanityn test 77s: Check TBF LRU shrinker ============== 21:17:17 (1777598237) [ 5249.527316] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 21:17:28 (1777598248) [ 5252.230044] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 21:17:31 (1777598251) [ 5264.776190] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 21:17:44 (1777598264) [ 5268.183363] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 21:17:47 (1777598267) [ 5268.567371] LustreError: 310086:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x799:0x0]: rc = -5 [ 5268.570951] LustreError: 310086:0:(llite_lib.c:3709:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5269.126582] LustreError: 310139:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x7a5:0x0]: rc = -5 [ 5269.129659] LustreError: 310139:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 8 previous similar messages [ 5269.132095] LustreError: 310139:0:(llite_lib.c:3709:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5269.134758] LustreError: 310139:0:(llite_lib.c:3709:ll_prep_inode()) Skipped 8 previous similar messages [ 5270.140224] LustreError: 310245:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x44:0x0]: rc = -5 [ 5270.143576] LustreError: 310245:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 19 previous similar messages [ 5270.146282] LustreError: 310245:0:(llite_lib.c:3709:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5270.148543] LustreError: 310245:0:(llite_lib.c:3709:ll_prep_inode()) Skipped 19 previous similar messages [ 5272.143592] LustreError: 310422:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x8e:0x0]: rc = -5 [ 5272.148301] LustreError: 310422:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 38 previous similar messages [ 5272.152192] LustreError: 310422:0:(llite_lib.c:3709:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5272.156163] LustreError: 310422:0:(llite_lib.c:3709:ll_prep_inode()) Skipped 38 previous similar messages [ 5376.413296] LustreError: 310660:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x86e:0x0]: rc = -5 [ 5376.423591] LustreError: 310660:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 79 previous similar messages [ 5376.431155] LustreError: 310660:0:(llite_lib.c:3709:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5376.437577] LustreError: 310660:0:(llite_lib.c:3709:ll_prep_inode()) Skipped 79 previous similar messages [ 5376.451645] LustreError: lustre-MDT0000-mdc-ffff9cf3aeab5000: operation mds_getattr_lock to node 192.168.202.124@tcp failed: rc = -107 [ 5376.459522] Lustre: lustre-MDT0000-mdc-ffff9cf3aeab5000: Connection to lustre-MDT0000 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5376.472816] LustreError: lustre-MDT0000-mdc-ffff9cf3aeab5000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5376.484643] LustreError: 310655:0:(file.c:6098:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 5376.484968] LustreError: 310677:0:(file.c:252:ll_close_inode_openhandle()) lustre-clilmv-ffff9cf3aeab5000: inode [0x2000013a1:0xd:0x0] mdc close failed: rc = -108 [ 5376.508765] Lustre: lustre-MDT0000-mdc-ffff9cf3aeab5000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 5387.820756] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 21:19:46 (1777598386) [ 5390.719811] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 21:19:49 (1777598389) [ 5461.449181] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 21:21:00 (1777598460) [ 5462.238608] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5463.160828] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 21:21:02 (1777598462) [ 5525.520504] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 21:22:04 (1777598524) [ 5528.746462] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 21:22:07 (1777598527) [ 5652.283933] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 21:24:11 (1777598651) [ 5661.257258] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 21:24:20 (1777598660) [ 5665.551711] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 21:24:24 (1777598664) [ 5849.234516] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 21:27:28 (1777598848) [ 5899.743247] Lustre: lustre-OST0001-osc-ffff9cf3aeab5000: disconnect after 24s idle [ 5899.748362] Lustre: Skipped 11 previous similar messages [ 6032.640592] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 21:30:31 (1777599031) [ 6036.024275] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 21:30:35 (1777599035) [ 6046.453576] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 21:30:45 (1777599045) [ 6046.557316] Lustre: DEBUG MARKER: write [ 6046.585914] LustreError: 288950:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6048.593709] Lustre: DEBUG MARKER: kill 354388 [ 6048.597285] LustreError: 354388:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6051.687141] LustreError: 288950:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 6054.639132] LustreError: 354388:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 6057.652645] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 21:30:56 (1777599056) [ 6059.850862] LustreError: 355002:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 6069.943130] LustreError: 355002:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [ 6072.609623] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 21:31:11 (1777599071) [ 6072.773770] LustreError: 355589:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6074.855118] LustreError: 355589:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [ 6081.557500] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 21:31:20 (1777599080) [ 6082.250403] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6083.112470] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 21:31:22 (1777599082) [ 6085.915655] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 21:31:25 (1777599085) [ 6088.812459] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 21:31:28 (1777599088) [ 6091.569708] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 21:31:30 (1777599090) [ 6094.607884] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 21:31:33 (1777599093) [ 6097.546302] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 21:31:36 (1777599096) [ 6100.460105] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 21:31:39 (1777599099) [ 6104.493584] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 21:31:43 (1777599103) [ 6108.128690] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 21:31:47 (1777599107) [ 6108.942761] Lustre: *** cfs_fail_loc=415, val=0*** [ 6115.864844] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 21:31:55 (1777599115) [ 6135.920556] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 21:32:15 (1777599135) [ 6136.007623] LustreError: 301534:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6136.010059] LustreError: 301534:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 6141.103067] LustreError: 288950:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 6141.105487] LustreError: 288950:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 2 previous similar messages [ 6151.295068] LustreError: 288949:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 6151.297143] LustreError: 288949:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 6159.314338] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 21:32:38 (1777599158) [ 6162.571450] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 21:32:41 (1777599161) [ 6166.344169] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 21:32:45 (1777599165) [ 6169.277239] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 21:32:48 (1777599168) [ 6174.205929] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 21:32:53 (1777599173) [ 6183.429670] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 21:33:02 (1777599182) [ 6183.693379] LustreError: 366322:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6183.698519] LustreError: 366322:0:(osc_request.c:2989:osc_build_rpc()) Skipped 6 previous similar messages [ 6187.759126] LustreError: 366322:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6187.764033] LustreError: 366322:0:(osc_request.c:2989:osc_build_rpc()) Skipped 3 previous similar messages [ 6190.925211] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 21:33:09 (1777599189) [ 6192.544187] LustreError: 367031:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9cf3aeab5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6192.548222] LustreError: 367031:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6192.579942] Lustre: Unmounted lustre-client [ 6193.189545] LustreError: 367051:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9cf389273000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6193.193757] LustreError: 367051:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6193.236425] Lustre: Unmounted lustre-client [ 6194.042869] Lustre: DEBUG MARKER: Iteration 0 [ 6194.191231] LustreError: 367215:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6194.191285] LustreError: 367216:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6194.196262] LustreError: 367215:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6194.252964] Lustre: Mounted lustre-client [ 6194.253981] Lustre: Skipped 1 previous similar message [ 6194.878520] LustreError: 367316:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9cf388d56800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6194.881386] LustreError: 367316:0:(lov_obd.c:792:lov_cleanup()) Skipped 3 previous similar messages [ 6194.940820] Lustre: Unmounted lustre-client [ 6196.161807] Key type lgssc unregistered [ 6196.341060] LNet: 367561:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6196.346491] LNetError: 367561:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6196.358402] LNet: Removed LNI 192.168.202.24@tcp [ 6196.844183] Key type .llcrypt unregistered [ 6196.847241] Key type ._llcrypt unregistered [ 6197.236755] Key type ._llcrypt registered [ 6197.237779] Key type .llcrypt registered [ 6197.491535] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6197.498506] alg: No test for adler32 (adler32-zlib) [ 6198.573932] Lustre: Lustre: Build Version: 2.17.52_126_gb75093b [ 6198.916069] LNet: Added LNI 192.168.202.24@tcp [8/256/0/180] [ 6200.551088] Key type lgssc registered [ 6201.304131] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6206.791688] Lustre: DEBUG MARKER: Iteration 1 [ 6206.958830] LustreError: 368392:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6206.959233] LustreError: 368393:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6206.963609] LustreError: 368392:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6208.028416] Lustre: Mounted lustre-client [ 6208.030487] Lustre: Skipped 1 previous similar message [ 6208.605613] LustreError: 368493:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9cf383c30000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6208.608696] LustreError: 368493:0:(lov_obd.c:792:lov_cleanup()) Skipped 2 previous similar messages [ 6208.651176] Lustre: Unmounted lustre-client [ 6209.996283] Key type lgssc unregistered [ 6210.104869] LNet: 368735:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6210.106981] LNetError: 368735:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6210.114353] LNet: Removed LNI 192.168.202.24@tcp [ 6210.342103] Key type .llcrypt unregistered [ 6210.342984] Key type ._llcrypt unregistered [ 6210.689169] Key type ._llcrypt registered [ 6210.698066] Key type .llcrypt registered [ 6210.885585] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6210.890253] alg: No test for adler32 (adler32-zlib) [ 6211.799659] Lustre: Lustre: Build Version: 2.17.52_126_gb75093b [ 6211.912233] LNet: Added LNI 192.168.202.24@tcp [8/256/0/180] [ 6213.535185] Key type lgssc registered [ 6214.218546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6218.763237] Lustre: DEBUG MARKER: Iteration 2 [ 6218.912529] LustreError: 369567:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6218.912562] LustreError: 369568:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6218.916945] LustreError: 369567:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6219.969567] Lustre: Mounted lustre-client [ 6220.553344] LustreError: 369670:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9cf382f8a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6220.556179] LustreError: 369670:0:(lov_obd.c:792:lov_cleanup()) Skipped 2 previous similar messages [ 6220.618182] Lustre: Unmounted lustre-client [ 6221.746075] Key type lgssc unregistered [ 6221.893841] LNet: 369914:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6221.900988] LNetError: 369914:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6221.914535] LNet: Removed LNI 192.168.202.24@tcp [ 6222.271208] Key type .llcrypt unregistered [ 6222.274231] Key type ._llcrypt unregistered [ 6222.639166] Key type ._llcrypt registered [ 6222.640257] Key type .llcrypt registered [ 6222.926163] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6222.931035] alg: No test for adler32 (adler32-zlib) [ 6223.829687] Lustre: Lustre: Build Version: 2.17.52_126_gb75093b [ 6223.933919] LNet: Added LNI 192.168.202.24@tcp [8/256/0/180] [ 6225.535130] Key type lgssc registered [ 6225.907475] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6231.170778] Lustre: Mounted lustre-client [ 6234.182789] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 21:33:53 (1777599233) [ 6250.975090] Lustre: 371255:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777599234/real 1777599234] req@ffff9cf3bd7ad880 x1863947882866560/t0(0) o36->lustre-MDT0000-mdc-ffff9cf3ba604000@192.168.202.124@tcp:12/10 lens 496/440 e 0 to 1 dl 1777599250 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6250.987536] Lustre: lustre-MDT0000-mdc-ffff9cf3ba604000: Connection to lustre-MDT0000 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6250.999072] Lustre: lustre-MDT0000-mdc-ffff9cf3ba604000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 6266.335098] Lustre: 371255:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777599250/real 1777599250] req@ffff9cf3bd7ad880 x1863947882866560/t0(0) o36->lustre-MDT0000-mdc-ffff9cf3ba604000@192.168.202.124@tcp:12/10 lens 496/440 e 0 to 1 dl 1777599266 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6266.347829] Lustre: lustre-MDT0000-mdc-ffff9cf3ba604000: Connection to lustre-MDT0000 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6266.357850] Lustre: lustre-MDT0000-mdc-ffff9cf3ba604000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 6282.719139] Lustre: 371255:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777599266/real 1777599266] req@ffff9cf3bd7ad880 x1863947882866560/t0(0) o36->lustre-MDT0000-mdc-ffff9cf3ba604000@192.168.202.124@tcp:12/10 lens 496/440 e 0 to 1 dl 1777599282 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6282.738206] Lustre: lustre-MDT0000-mdc-ffff9cf3ba604000: Connection to lustre-MDT0000 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6282.759662] Lustre: lustre-MDT0000-mdc-ffff9cf3ba604000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 6299.103159] Lustre: 371255:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777599282/real 1777599282] req@ffff9cf3bd7ad880 x1863947882866560/t0(0) o36->lustre-MDT0000-mdc-ffff9cf3ba604000@192.168.202.124@tcp:12/10 lens 496/440 e 0 to 1 dl 1777599298 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6299.120873] Lustre: lustre-MDT0000-mdc-ffff9cf3ba604000: Connection to lustre-MDT0000 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6299.137525] Lustre: lustre-MDT0000-mdc-ffff9cf3ba604000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 6299.928352] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 21:34:59 (1777599299) [ 6306.667385] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 21:35:05 (1777599305) [ 6311.878567] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 21:35:11 (1777599311) [ 6315.313808] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 21:35:14 (1777599314) [ 6326.158556] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 21:35:25 (1777599325) [ 6343.774325] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 21:35:42 (1777599342) [ 6347.079841] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 21:35:46 (1777599346) [ 6347.149096] LustreError: 376028:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 6349.231138] LustreError: 376028:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 6352.246460] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 21:35:51 (1777599351) [ 6370.783240] Lustre: 370109:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777599354/real 1777599354] req@ffff9cf3aa14c380 x1863947883906432/t0(0) o4->lustre-OST0000-osc-ffff9cf3ba604000@192.168.202.124@tcp:6/4 lens 4584/448 e 0 to 1 dl 1777599370 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6370.783324] Lustre: lustre-OST0000-osc-ffff9cf3ba604000: Connection to lustre-OST0000 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6370.800094] Lustre: 370109:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 6370.819690] Lustre: lustre-OST0000-osc-ffff9cf3ba604000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 6387.167218] Lustre: 370110:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777599370/real 1777599370] req@ffff9cf3afba6300 x1863947883905664/t0(0) o4->lustre-OST0000-osc-ffff9cf3ba604000@192.168.202.124@tcp:6/4 lens 4584/448 e 0 to 1 dl 1777599386 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6387.167280] Lustre: lustre-OST0000-osc-ffff9cf3ba604000: Connection to lustre-OST0000 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6387.183459] Lustre: 370110:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 6387.204258] Lustre: lustre-OST0000-osc-ffff9cf3ba604000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 6418.911301] Lustre: 370110:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777599402/real 1777599402] req@ffff9cf3afba6300 x1863947883905664/t0(0) o4->lustre-OST0000-osc-ffff9cf3ba604000@192.168.202.124@tcp:6/4 lens 4584/448 e 0 to 1 dl 1777599418 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6418.911301] Lustre: 370108:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777599402/real 1777599402] req@ffff9cf396d8ea00 x1863947883906816/t0(0) o4->lustre-OST0000-osc-ffff9cf3ba604000@192.168.202.124@tcp:6/4 lens 4584/448 e 0 to 1 dl 1777599418 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6418.911323] Lustre: 370110:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 6418.918697] Lustre: 370108:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 6418.926016] Lustre: lustre-OST0000-osc-ffff9cf3ba604000: Connection to lustre-OST0000 (at 192.168.202.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6418.934742] Lustre: Skipped 1 previous similar message [ 6418.941839] Lustre: lustre-OST0000-osc-ffff9cf3ba604000: Connection restored to 192.168.202.124@tcp (at 192.168.202.124@tcp) [ 6418.948245] Lustre: Skipped 1 previous similar message [ 6443.483895] Lustre: DEBUG MARKER: oleg224-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cf383153000.ost_server_uuid 50 [ 6443.948691] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cf383153000.ost_server_uuid in IDLE state after 0 sec [ 6444.467684] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 6445.341698] Lustre: DEBUG MARKER: == sanityn test complete, duration 6318 sec ============== 21:37:24 (1777599444) [ 6446.111360] Lustre: DEBUG MARKER: === sanityn: start cleanup 21:37:25 (1777599445) === [ 6552.564406] LustreError: 378083:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9cf383153000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6552.592085] Lustre: Unmounted lustre-client [ 6554.404328] Lustre: DEBUG MARKER: === sanityn: finish cleanup 21:39:13 (1777599553) === [ 6554.768521] LustreError: 378387:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9cf3ba604000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6554.772464] LustreError: 378387:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6554.806093] Lustre: Unmounted lustre-client [ 6594.719966] Key type lgssc unregistered [ 6594.860481] LNet: 379069:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6594.865124] LNetError: 379069:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6594.876783] LNet: Removed LNI 192.168.202.24@tcp [ 6595.237164] Key type .llcrypt unregistered [ 6595.239480] Key type ._llcrypt unregistered