[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 483182269 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2640MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002396] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004015] 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.008021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009012] pid_max: default: 32768 minimum: 301 [ 0.011089] LSM: Security Framework initializing [ 0.012050] Yama: becoming mindful. [ 0.013035] SELinux: Initializing. [ 0.014077] *** VALIDATE selinux *** [ 0.023563] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027722] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028116] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029089] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030120] *** VALIDATE tmpfs *** [ 0.032271] *** VALIDATE proc *** [ 0.033220] *** VALIDATE cgroup *** [ 0.034008] *** VALIDATE cgroup2 *** [ 0.035246] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036162] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039023] Spectre V2 : User space: Vulnerable [ 0.040009] Speculative Store Bypass: Vulnerable [ 0.043356] debug: unmapping init [mem 0xffffffff8fa59000-0xffffffff8fa60fff] [ 0.045251] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046671] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047023] ... version: 2 [ 0.048013] ... bit width: 48 [ 0.049012] ... generic registers: 4 [ 0.050012] ... value mask: 0000ffffffffffff [ 0.051015] ... max period: 00007fffffffffff [ 0.052014] ... fixed-purpose events: 3 [ 0.053012] ... event mask: 000000070000000f [ 0.055328] rcu: Hierarchical SRCU implementation. [ 0.057486] smp: Bringing up secondary CPUs ... [ 0.058649] x86: Booting SMP configuration: [ 0.059023] .... node #0, CPUs: #1 #2 #3 [ 0.066262] smp: Brought up 1 node, 4 CPUs [ 0.068014] smpboot: Max logical packages: 1 [ 0.069014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.171588] node 0 deferred pages initialised in 100ms [ 0.173131] devtmpfs: initialized [ 0.174229] x86/mm: Memory block size: 128MB [ 0.176255] gcov: version magic: 0x41383552 [ 0.179477] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.190117] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.193425] pinctrl core: initialized pinctrl subsystem [ 0.196212] [ 0.196867] ************************************************************* [ 0.199018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.202015] ** ** [ 0.205013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.208019] ** ** [ 0.211031] ** This means that this kernel is built to expose internal ** [ 0.213019] ** IOMMU data structures, which may compromise security on ** [ 0.217020] ** your system. ** [ 0.221022] ** ** [ 0.234026] ** If you see this message and you are not debugging the ** [ 0.236035] ** kernel, report this immediately to your vendor! ** [ 0.254020] ** ** [ 0.256015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.269020] ************************************************************* [ 0.275230] NET: Registered protocol family 16 [ 0.281133] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.309074] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.314073] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.319194] cpuidle: using governor menu [ 0.320937] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.323652] PCI: Using configuration type 1 for base access [ 0.326166] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.337091] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.338014] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.340027] cryptd: max_cpu_qlen set to 1000 [ 0.343002] ACPI: Added _OSI(Module Device) [ 0.344016] ACPI: Added _OSI(Processor Device) [ 0.346010] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.348011] ACPI: Added _OSI(Processor Aggregator Device) [ 0.354000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.362651] ACPI: Interpreter enabled [ 0.364111] ACPI: PM: (supports S0 S3 S4 S5) [ 0.366011] ACPI: Using IOAPIC for interrupt routing [ 0.369096] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.373557] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.386986] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.390038] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.393029] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.398081] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.405627] acpiphp: Slot [2] registered [ 0.407156] acpiphp: Slot [5] registered [ 0.409147] acpiphp: Slot [6] registered [ 0.410242] acpiphp: Slot [3] registered [ 0.412162] acpiphp: Slot [4] registered [ 0.414257] acpiphp: Slot [7] registered [ 0.416090] acpiphp: Slot [8] registered [ 0.418178] acpiphp: Slot [9] registered [ 0.420127] acpiphp: Slot [10] registered [ 0.422097] acpiphp: Slot [11] registered [ 0.424157] acpiphp: Slot [12] registered [ 0.426199] acpiphp: Slot [13] registered [ 0.428104] acpiphp: Slot [14] registered [ 0.430101] acpiphp: Slot [15] registered [ 0.431099] acpiphp: Slot [16] registered [ 0.433107] acpiphp: Slot [17] registered [ 0.435098] acpiphp: Slot [18] registered [ 0.436149] acpiphp: Slot [19] registered [ 0.438088] acpiphp: Slot [20] registered [ 0.440092] acpiphp: Slot [21] registered [ 0.441000] acpiphp: Slot [22] registered [ 0.441000] acpiphp: Slot [23] registered [ 0.444075] acpiphp: Slot [24] registered [ 0.446087] acpiphp: Slot [25] registered [ 0.448136] acpiphp: Slot [26] registered [ 0.449086] acpiphp: Slot [27] registered [ 0.451085] acpiphp: Slot [28] registered [ 0.453095] acpiphp: Slot [29] registered [ 0.455121] acpiphp: Slot [30] registered [ 0.457093] acpiphp: Slot [31] registered [ 0.459060] PCI host bridge to bus 0000:00 [ 0.461018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.463022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.466017] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.470021] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.472016] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.474015] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.476175] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.479101] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.482428] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.489774] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.493057] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.496019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.498025] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.499000] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.500566] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.521000] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.524047] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.528632] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.534000] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.545617] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.551995] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.560084] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.566014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.573016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.601020] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.618077] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.625013] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.640014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.668022] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.685567] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.689550] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.693556] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.698322] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.700167] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.706124] iommu: Default domain type: Passthrough [ 0.707000] SCSI subsystem initialized [ 0.709188] ACPI: bus type USB registered [ 0.710132] usbcore: registered new interface driver usbfs [ 0.711000] usbcore: registered new interface driver hub [ 0.711000] usbcore: registered new device driver usb [ 0.712275] pps_core: LinuxPPS API ver. 1 registered [ 0.714012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.717124] PTP clock support registered [ 0.720121] EDAC MC: Ver: 3.0.0 [ 0.723143] PCI: Using ACPI for IRQ routing [ 0.726103] NetLabel: Initializing [ 0.728014] NetLabel: domain hash size = 128 [ 0.729014] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.732210] NetLabel: unlabeled traffic allowed by default [ 0.737275] vgaarb: loaded [ 0.739519] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.742021] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.755622] clocksource: Switched to clocksource kvm-clock [ 0.868584] VFS: Disk quotas dquot_6.6.0 [ 0.870456] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.872980] *** VALIDATE ramfs *** [ 0.873886] *** VALIDATE hugetlbfs *** [ 0.875092] pnp: PnP ACPI init [ 0.877409] pnp: PnP ACPI: found 6 devices [ 0.895204] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.898567] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.901112] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.903391] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.906598] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.909653] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.913299] NET: Registered protocol family 2 [ 0.915423] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.920020] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.922985] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.928032] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.931110] TCP: Hash tables configured (established 65536 bind 65536) [ 0.934412] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.937519] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.942569] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.946070] NET: Registered protocol family 1 [ 0.948880] RPC: Registered named UNIX socket transport module. [ 0.951573] RPC: Registered udp transport module. [ 0.953805] RPC: Registered tcp transport module. [ 0.956064] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.959462] NET: Registered protocol family 44 [ 0.961792] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.964304] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.966849] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.969373] PCI: CLS 0 bytes, default 64 [ 0.971359] Unpacking initramfs... [ 2.703350] debug: unmapping init [mem 0xffff8de3fcc64000-0xffff8de3fffcffff] [ 2.707936] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.710249] software IO TLB: mapped [mem 0x00000000a1000000-0x00000000a5000000] (64MB) [ 2.713797] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.282470] Initialise system trusted keyrings [ 3.284484] Key type blacklist registered [ 3.290809] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.300793] zbud: loaded [ 3.303869] *** VALIDATE nfs *** [ 3.305334] *** VALIDATE nfs4 *** [ 3.307033] pstore: using deflate compression [ 3.310982] Platform Keyring initialized [ 3.442634] NET: Registered protocol family 38 [ 3.444096] Key type asymmetric registered [ 3.445172] Asymmetric key parser 'x509' registered [ 3.447942] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.450224] io scheduler mq-deadline registered [ 3.451126] io scheduler kyber registered [ 3.452025] io scheduler bfq registered [ 3.453285] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.456170] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.459154] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.461185] ACPI: Power Button [PWRF] [ 3.468686] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.475477] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.493185] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.521766] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.549870] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.554398] Non-volatile memory driver v1.3 [ 3.556084] Linux agpgart interface v0.103 [ 3.584954] virtio_blk virtio1: [vda] 139280 512-byte logical blocks (71.3 MB/68.0 MiB) [ 3.587334] vda: detected capacity change from 0 to 71311360 [ 3.601289] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.604130] vdb: detected capacity change from 0 to 1073741824 [ 3.611763] libphy: Fixed MDIO Bus: probed [ 3.616556] usbcore: registered new interface driver usbserial_generic [ 3.618562] usbserial: USB Serial support registered for generic [ 3.620846] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.626372] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.642352] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.650709] mousedev: PS/2 mouse device common for all mice [ 3.653682] rtc_cmos 00:05: RTC can wake from S4 [ 3.656677] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.658323] rtc_cmos 00:05: registered as rtc0 [ 3.662792] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.665778] intel_pstate: CPU model not supported [ 3.668793] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.676200] hid: raw HID events driver (C) Jiri Kosina [ 3.680566] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.682485] usbcore: registered new interface driver usbhid [ 3.690089] usbhid: USB HID core driver [ 3.699158] drop_monitor: Initializing network drop monitor service [ 3.702218] Initializing XFRM netlink socket [ 3.705788] NET: Registered protocol family 10 [ 3.733450] Segment Routing with IPv6 [ 3.735089] NET: Registered protocol family 17 [ 3.738663] mpls_gso: MPLS GSO support [ 3.744149] RAS: Correctable Errors collector initialized. [ 3.747248] AVX version of gcm_enc/dec engaged. [ 3.750172] AES CTR mode by8 optimization enabled [ 3.828840] sched_clock: Marking stable (3828822676, 0)->(4808148331, -979325655) [ 3.832789] registered taskstats version 1 [ 3.835436] Loading compiled-in X.509 certificates [ 3.837695] zswap: loaded using pool lzo/zbud [ 3.879936] Key type big_key registered [ 3.892977] Key type encrypted registered [ 3.896822] ima: No TPM chip found, activating TPM-bypass! [ 3.899275] ima: Allocated hash algorithm: sha1 [ 3.901402] ima: No architecture policies found [ 3.903524] evm: Initialising EVM extended attributes: [ 3.905875] evm: security.selinux [ 3.907468] evm: security.ima [ 3.908930] evm: security.capability [ 3.910485] evm: HMAC attrs: 0x1 [ 3.913333] rtc_cmos 00:05: setting system clock to 2026-06-16 13:28:35 UTC (1781616515) [ 3.921570] debug: unmapping init [mem 0xffffffff90a03000-0xffffffff90bfffff] [ 3.925740] debug: unmapping init [mem 0xffffffff8f782000-0xffffffff8fa58fff] [ 3.935294] Write protecting the kernel read-only data: 28672k [ 3.938965] debug: unmapping init [mem 0xffffffff8de03000-0xffffffff8dffffff] [ 3.942255] debug: unmapping init [mem 0xffffffff8e714000-0xffffffff8e7fffff] [ 4.014349] 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) [ 4.025480] systemd[1]: Detected virtualization kvm. [ 4.028201] systemd[1]: Detected architecture x86-64. [ 4.030514] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.067565] systemd[1]: No hostname configured. [ 4.069605] systemd[1]: Set hostname to . [ 4.072271] random: systemd: uninitialized urandom read (16 bytes read) [ 4.075333] systemd[1]: Initializing machine ID from random generator. [ 4.136060] random: ln: uninitialized urandom read (6 bytes read) [ 4.315165] random: systemd: uninitialized urandom read (16 bytes read) [ 4.318659] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 4.325409] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 4.334470] systemd[1]: Started Memstrack Anylazing Service. [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Paths. Starting Setup Virtual Console... [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Slices. [ OK ] Reached target Swap. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ 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... [ 5.334496] device-mapper: uevent: version 1.0.3 [ 5.336424] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 8.001162] virtio_net virtio0 ens2: renamed from eth0 [ 10.000348] scsi host0: ata_piix [ 10.233217] scsi host1: ata_piix [ 10.235087] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 10.237835] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 14.441535] random: fast init done [ 15.734612] random: crng init done [ 15.737170] random: 7 urandom warning(s) missed due to ratelimiting [ 18.541276] dracut-initqueue[593]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 19.300620] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ 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 Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 21.303158] printk: systemd: 25 output lines suppressed due to ratelimiting [ 21.712781] SELinux: Disabled at runtime. [ 21.787144] 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) [ 21.795646] systemd[1]: Detected virtualization kvm. [ 21.797712] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 22.662407] systemd[1]: initrd-switch-root.service: Succeeded. [ 22.665495] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 22.702498] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 22.707663] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 22.712182] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 22.728897] systemd[1]: Starting Journal Service... Starting Journal Service... [ 22.740504] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting POSIX Message Queue File System... Mounting Kernel Debug File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ 22.916249] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Apply Kernel Variables... Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Control Socket. [ OK ] Reached target RPC Port Mapper. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice User and Session Slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-getty.slice. Starting udev Coldplug all Devices... [ OK ] Reached target Slices. [ OK ] Reached target Paths. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 24.752876] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 26.207696] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 26.236332] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 26.792995] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 26.965861] EDAC sbridge: Ver: 1.1.2 [ 29.250607] Key type dns_resolver registered [ 29.644346] NFS: Registering the id_resolver key type [ 29.646372] Key type id_resolver registered [ 29.648118] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] 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 Timers. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Restore /run/initramfs on shutdown... Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Login Service. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting 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... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg401-client login: [ 78.294951] libcfs: loading out-of-tree module taints kernel. [ 78.339078] Key type ._llcrypt registered [ 78.341229] Key type .llcrypt registered [ 78.605920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 78.620570] alg: No test for adler32 (adler32-zlib) [ 79.899613] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 80.483178] LNet: Added LNI 192.168.204.1@tcp [8/256/0/180] [ 82.168172] Key type lgssc registered [ 83.402184] Lustre: Echo OBD driver; http://www.lustre.org/ [ 188.783285] Lustre: Mounted lustre-client [ 191.861272] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 202.137640] Lustre: DEBUG MARKER: oleg401-client.virtnet: executing check_logdir /tmp/testlogs/ [ 204.797629] Lustre: DEBUG MARKER: oleg401-client.virtnet: executing yml_node [ 207.334303] Lustre: DEBUG MARKER: Client: 2.17.54.40 [ 208.946950] Lustre: DEBUG MARKER: MDS: 2.17.54.40 [ 210.407849] Lustre: DEBUG MARKER: OSS: 2.17.54.40 [ 211.341698] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Tue Jun 16 09:32:02 EDT 2026 [ 214.499308] Lustre: lustre-OST0000-osc-ffff8de447df8800: disconnect after 24s idle [ 222.276618] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 223.184429] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 224.266805] Lustre: DEBUG MARKER: === sanityn: start setup 09:32:14 (1781616734) === [ 224.705553] Lustre: Mounted lustre-client [ 228.087708] Lustre: DEBUG MARKER: oleg401-client.virtnet: executing check_config_client /mnt/lustre [ 239.823991] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 245.216614] Lustre: lustre-OST0000-osc-ffff8de4435df800: disconnect after 20s idle [ 245.221717] Lustre: Skipped 1 previous similar message [ 247.548184] Lustre: DEBUG MARKER: === sanityn: finish setup 09:32:38 (1781616758) === [ 249.149959] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 09:32:39 (1781616759) [ 254.649484] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 09:32:45 (1781616765) [ 258.914573] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 09:32:49 (1781616769) [ 262.590988] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 09:32:53 (1781616773) [ 266.619344] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 09:32:57 (1781616777) [ 270.721766] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 09:33:01 (1781616781) [ 274.863249] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 09:33:05 (1781616785) [ 279.335172] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 09:33:09 (1781616789) [ 284.188203] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 09:33:14 (1781616794) [ 288.536643] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 09:33:19 (1781616799) [ 292.526733] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 09:33:23 (1781616803) [ 297.541101] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 09:33:28 (1781616808) [ 301.921131] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 09:33:32 (1781616812) [ 306.154815] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 09:33:36 (1781616816) [ 310.169625] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 09:33:40 (1781616820) [ 314.232924] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 09:33:44 (1781616824) [ 318.772296] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 09:33:49 (1781616829) [ 322.814484] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 09:33:53 (1781616833) [ 327.941195] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 09:33:58 (1781616838) [ 332.095382] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 09:34:02 (1781616842) [ 336.852989] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 09:34:07 (1781616847) [ 337.207914] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 474.729904] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 09:36:25 (1781616985) [ 478.258337] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 09:36:29 (1781616989) [ 481.543409] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 09:36:32 (1781616992) [ 484.900501] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 09:36:35 (1781616995) [ 488.588926] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 09:36:39 (1781616999) [ 492.427907] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 09:36:43 (1781617003) [ 493.418218] Lustre: DEBUG MARKER: chmod [ 496.721126] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 09:36:47 (1781617007) [ 1057.051732] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 09:46:07 (1781617567) [ 1072.762944] hrtimer: interrupt took 9295941 ns [ 1171.936337] Lustre: lustre-OST0001-osc-ffff8de4435df800: disconnect after 24s idle [ 1171.943555] Lustre: Skipped 1 previous similar message [ 1208.185022] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 09:48:39 (1781617719) [ 1272.651710] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 09:49:43 (1781617783) [ 1342.062084] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 09:50:53 (1781617853) [ 1353.811913] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 09:51:04 (1781617864) [ 1356.263668] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 09:51:07 (1781617867) [ 1356.712851] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.744429] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.765633] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.786513] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.807692] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.829148] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.852174] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.873638] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.895930] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.922425] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.944436] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.966067] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1356.988948] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.010374] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.032513] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.054991] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.079760] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.107774] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.135196] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.158466] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.180539] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.203674] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.229728] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.253597] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.277512] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.300632] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.323550] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.348925] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.370820] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.393572] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.418754] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.444141] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.467049] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.488164] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.513410] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.538392] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.563274] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.587430] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.608373] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.629357] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.653666] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.675559] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.705339] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.731606] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.760776] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.784516] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.815787] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.842619] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.867118] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.890669] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.912294] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.935043] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.971348] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1357.998643] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.024481] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.050127] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.080708] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.101158] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.122689] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.146636] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.169162] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.194127] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.228347] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.252966] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.276050] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.297640] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.321476] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.345655] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.372722] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.401260] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.432847] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.462190] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.491847] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.518649] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.543767] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.567967] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.593354] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.621664] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.648452] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.674849] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.702773] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.728986] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.753088] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.777231] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.804223] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.832054] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.860953] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.886936] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.917636] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.947519] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.972985] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1358.994837] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.019184] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.043167] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.067338] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.091325] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.111449] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.134741] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.156335] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.178980] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.202165] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.227030] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.249677] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.273137] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.298669] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.326238] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.352688] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.377632] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.401997] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.425766] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.448293] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.469128] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.490400] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.511135] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.535663] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.560958] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.584723] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.611235] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.636883] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.661080] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.684242] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.706974] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.728051] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.753080] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.776365] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.797100] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.819711] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.842919] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.863719] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.884726] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.907082] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.936927] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.967848] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1359.995982] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.021955] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.044207] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.067143] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.094514] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.124401] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.148768] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.172585] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.194534] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.216360] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.239534] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.260389] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.284214] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.310810] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.331793] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.356053] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.380715] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.406301] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.427280] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.447563] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.467534] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.490917] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.511367] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.529822] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.549959] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.572268] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.593463] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.613423] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.634990] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.654432] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.674964] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.695589] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.727397] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.757370] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.787964] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.815121] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.837313] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.860551] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.882313] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.903532] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.925816] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.947353] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.969927] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1360.991152] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.011971] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.034326] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.057423] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.077384] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.097526] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.117680] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.137213] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.158207] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.181061] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.206272] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.230391] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.257983] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.278940] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.299532] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.320956] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.342046] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.362628] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.384336] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.404984] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.424446] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.446066] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.469065] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.492583] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.513825] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.537287] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.558774] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.580612] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.605746] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.631361] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.657193] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.679409] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.701574] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.725392] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.750795] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.774961] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.796140] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.820717] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.843052] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.864304] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.884257] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.905565] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.931449] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.954779] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1361.978333] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.003663] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.033572] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.057230] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.079711] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.099908] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.122331] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.145592] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.171826] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.199717] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.229127] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.257293] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.280536] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.302795] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.325022] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.347212] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.369919] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.392964] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.414134] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.435387] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.457914] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.479154] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.500421] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.522775] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.546692] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.569157] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.592022] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.616380] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.639707] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.664834] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.687632] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.710868] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.732759] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.754864] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.775434] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.798869] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.820210] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.843661] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.867112] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.888755] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.908809] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.932138] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.956934] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1362.981541] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.006694] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.028856] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.052222] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.074871] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.097516] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.120075] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.142154] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.165887] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.187866] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.210683] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.242596] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.270897] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.293339] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.314930] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.338761] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.361112] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.382561] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.406935] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.430703] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.451951] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.474665] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.495786] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.516144] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.536424] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.557431] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.580705] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.601275] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.621657] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.642277] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.662864] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.681639] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.703648] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.725815] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.747718] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.770947] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.796083] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.816473] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.837708] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.858685] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.878983] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.900559] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.921294] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.943115] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.963437] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1363.982934] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.004182] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.027856] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.048532] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.069634] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.090927] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.112426] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.136203] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.161890] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.184594] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.208879] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.233256] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.255873] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.277460] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.298105] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.318585] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.343598] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.364989] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.386063] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.408216] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.427578] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.455741] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.479996] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.505681] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.528310] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.549650] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.573764] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.594243] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.614311] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.634878] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.655416] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.675720] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.696926] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.719390] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.740440] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.761858] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.782936] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.803960] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.826759] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.846530] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.868626] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.890258] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.910693] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.934094] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.957536] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.978202] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1364.999861] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.022017] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.044975] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.071334] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.095516] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.118203] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.139692] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.161035] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.184728] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.208541] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.230560] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.251632] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.273967] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.297973] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.322086] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.352683] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.379489] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.404536] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.426051] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.449746] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.474740] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.499178] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.519398] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.539379] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.559201] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.579690] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.600185] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.622330] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.643760] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.666455] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.687894] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.713572] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.735364] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.758516] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.780902] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.805801] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.829544] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.852043] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.873494] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.895407] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.917109] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.941844] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.968478] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1365.995646] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.023154] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.048707] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.082224] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.113364] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.145308] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.168038] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.191651] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.214031] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.237168] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.258433] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.281460] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.304710] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.327870] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.351644] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.375386] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.399723] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.423050] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.445341] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.465965] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.497066] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.524505] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.551656] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.576635] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.601202] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.623801] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.647099] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.669097] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.693376] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.718658] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.744582] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.778113] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.802972] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.828921] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.858297] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.880072] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.901190] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.919900] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.940055] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.961260] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1366.983087] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.003824] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.024737] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.045775] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.067283] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.087352] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.108274] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.129344] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.149718] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.173248] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.196919] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.218794] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.241873] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.264875] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.287280] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.308676] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.334884] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.357305] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.379297] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.401788] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.422115] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.451170] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.474391] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.496745] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.519417] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.544811] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.571521] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.596802] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.618810] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.638580] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.661376] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.684068] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.706421] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.729700] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.750993] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.771719] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.797030] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.826901] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.850462] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.876312] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.900699] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.923977] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.946806] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.970386] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1367.989704] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.011966] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.034525] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.055776] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.079213] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.100769] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.123870] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.146467] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.167593] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.190057] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.212187] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.234840] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.257850] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.281349] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.304140] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.326097] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.348752] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.370985] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.396213] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.421430] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.443086] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.464671] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.486329] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.509908] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.532785] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.556689] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.585722] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.610070] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.631040] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.650958] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.675144] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.698037] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.722905] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.747712] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.772663] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.795741] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.817774] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.841324] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.867860] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.894744] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.919493] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.944905] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.968875] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1368.995052] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.017153] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.042480] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.071569] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.095855] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.118173] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.140988] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.165716] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.190339] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.217087] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.242660] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.271246] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.295939] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.317945] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.339804] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.363610] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.388463] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.411538] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.435278] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.456777] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.478465] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.500901] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.529814] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.557675] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.583991] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.608536] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.631278] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.652845] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.675123] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.698564] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.722824] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.746840] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.771867] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.794989] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.821675] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.848872] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.872701] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.898023] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.924212] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.949386] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.975548] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1369.999737] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.024631] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.048906] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.082512] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.107864] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.131114] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.154167] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.175455] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.198363] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.221552] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.243709] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.266793] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.289353] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.311908] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.334454] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.358398] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.382757] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.408258] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.433769] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.457840] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.479179] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.500365] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.524404] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.546890] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.570469] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.596391] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.618399] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.643243] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.667053] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.690129] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.714026] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.739231] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.766191] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.794584] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.831982] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.854934] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.876428] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.902059] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.934774] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.961816] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1370.988402] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.012930] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.037237] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.060174] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.083235] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.112722] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.136508] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.162842] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.185209] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.210594] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.234658] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.256807] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.281502] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.302706] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.324391] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.346950] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.371449] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.395707] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.421469] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.445619] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.467416] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.492728] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.514866] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.536589] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.557508] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.578963] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.605195] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.629340] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.651727] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.677353] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.700645] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.727516] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.750890] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.774078] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.797923] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.823137] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.849112] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.873759] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.897884] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.922928] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.947104] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.970974] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1371.994397] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.019940] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.048513] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.074357] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.097893] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.120464] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.145731] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.177230] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.204865] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.230259] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.258230] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.283628] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.310726] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.336389] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.365627] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.390557] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.417772] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.443113] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.468467] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.499789] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.526547] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.551522] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.575598] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.600065] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.626429] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.648931] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.671121] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.694665] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.720496] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.746550] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.770874] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.795297] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.821095] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.843679] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.868029] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.890691] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.914025] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.939957] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.963628] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1372.989351] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.014355] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.037749] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.063249] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.095204] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.118395] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.143658] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.166410] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.199496] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.224037] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.246939] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.272115] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.296475] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.322570] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.347482] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.378629] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.407225] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.432807] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.456851] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.484781] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.509454] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.532908] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.557126] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.579978] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.604440] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.627226] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.650348] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.674329] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.696150] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.718455] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.744629] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.770193] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.808670] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.841108] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.867476] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.896524] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.917713] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.944107] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.972672] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1373.999196] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.023841] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.045855] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.070214] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.092418] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.116965] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.140128] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.164184] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.189419] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.216198] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.239995] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.264210] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.289977] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.312748] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.335356] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.356386] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.377674] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.399721] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.423951] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.447521] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.467433] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.488412] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.513924] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.534389] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.555740] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.575916] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.595612] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.617095] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.638771] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.659595] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.680661] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.703663] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.727490] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.748559] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.770660] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.791979] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.815635] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.837652] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.859086] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.880085] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.902089] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.927017] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.949116] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.970241] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1374.992523] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.019160] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.049730] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.079527] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.105379] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.131543] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.155073] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.183333] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.208328] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.238346] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.273411] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.298152] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.323441] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.348943] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.378120] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.403473] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.428932] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.452456] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.477093] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.500917] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.524183] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.548542] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.571894] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.605744] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.639322] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.668763] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.691873] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.715096] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.738982] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.764695] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.789169] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.813280] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.836123] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.859165] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.881972] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.902202] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.927684] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.951924] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1375.978100] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.002569] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.026797] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.053380] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.082664] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.111729] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.134478] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.157108] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.182322] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.213097] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.239148] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.265856] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.290683] rw_seq_cst_vs_d (32488): drop_caches: 3 [ 1376.736680] Lustre: lustre-OST0000-osc-ffff8de4435df800: disconnect after 23s idle [ 1378.844806] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 09:51:29 (1781617889) [ 1378.960414] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1378.980397] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.043839] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.091644] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.116282] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.134974] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.181657] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.202673] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.224448] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.267793] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.329155] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.349236] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.371394] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.398743] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.438996] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.465709] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.486127] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.542109] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.569025] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.588068] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.631994] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.678031] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.726835] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.744331] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.803926] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.850155] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.866426] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.884430] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.922955] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1379.966804] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.014649] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.086455] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.132808] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.169485] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.259103] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.277496] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.335859] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.372014] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.417183] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.444670] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.469093] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.499719] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.524305] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.539760] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.563936] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.637641] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.653184] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.668214] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.684402] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.703180] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.719378] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.737039] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.757633] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.780548] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.795573] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.813459] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.978539] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1380.997610] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.015573] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.033362] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.103252] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.127614] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.151130] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.171326] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.211529] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.251592] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.270100] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.385920] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.406832] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.421920] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.438608] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.633261] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.654972] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.672422] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.692202] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.711469] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.727972] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.747437] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.764249] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.781511] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.936669] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1381.956684] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.115405] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.184344] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.226873] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.268937] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.289846] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.311190] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.329209] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.347752] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.391427] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.475488] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.521589] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.545270] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.565654] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.584365] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.602240] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.635722] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.703527] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.721588] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.801763] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.861162] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.880491] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.898967] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.921399] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.938014] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1382.995543] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.031197] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.047754] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.063274] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.079311] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.097967] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.201526] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.534571] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.557309] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.667599] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.682710] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.701174] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.722437] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.738807] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.755577] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.776631] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.791962] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.806193] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.821670] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.842352] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.861534] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.882691] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.901065] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.921169] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.937237] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.970172] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1383.991426] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.009820] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.107734] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.132747] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.167845] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.203464] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.218279] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.235216] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.253081] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.268512] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.283271] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.300771] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.337366] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.352185] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.368404] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.389401] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.404409] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.422288] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.439381] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.477211] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.495832] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.510321] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.525467] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.542579] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.727924] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.772831] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.792852] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.810103] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.827476] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.944598] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.959674] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1384.976516] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.006203] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.024609] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.045762] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.068717] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.087535] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.106892] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.125170] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.142817] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.162456] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.177291] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.197570] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.220123] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.239556] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.259490] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.280277] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.297795] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.366979] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.459260] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.481217] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.502649] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.519366] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.537863] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.560242] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.578979] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.600220] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.636262] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.657891] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.780797] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.799956] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.816409] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.833429] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.851548] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.872716] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.888615] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1385.906055] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.058196] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.077957] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.093762] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.108862] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.242562] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.274046] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.294227] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.334784] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.355086] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.393905] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.409604] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.425884] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.440898] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.460023] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.472484] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.490069] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.548065] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.568145] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.585203] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.620636] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.634986] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.655716] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.689220] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.704917] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.724650] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.746514] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.765266] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.811308] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.834519] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.858689] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.940363] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.956690] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.972851] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1386.990057] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.008980] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.023564] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.042959] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.062603] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.081936] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.100104] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.122096] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.176458] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.191836] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.206407] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.221640] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.252767] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.268265] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.282437] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.299077] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.314669] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.333061] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.347181] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.368067] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.404791] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.438195] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.469079] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.501608] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.534747] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.550263] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.569001] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.600662] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.634131] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.664294] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.677133] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.692501] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.724076] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.738957] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.757593] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.777165] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.792919] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.817399] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.835384] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.852084] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.868654] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.888805] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.904733] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.922816] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.937383] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.951844] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.970136] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.984174] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1387.997828] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.013573] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.029306] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.048296] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.066980] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.087234] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.123831] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.141287] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.226406] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.352529] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.501135] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.519624] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.598945] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.614919] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.630944] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.648104] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.667974] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.688651] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.740230] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.759747] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.775780] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.791792] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.807458] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.825219] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.843291] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.860545] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.879692] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.894085] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.910038] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1388.975448] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.055020] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.077731] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.125085] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.145221] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.165100] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.184160] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.201709] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.216761] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.254847] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.293017] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.309182] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.324324] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.343585] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.363446] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.379066] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.395583] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.467954] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.482041] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.496386] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.514565] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.530035] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.599837] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.614353] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.627681] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.643405] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.660377] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.674666] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.693238] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.707377] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.722414] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.741215] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.762228] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.851307] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.868426] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.883267] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.901264] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.922062] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1389.942785] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.003131] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.021354] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.040798] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.062917] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.176034] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.194977] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.209490] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.229089] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.244744] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.279245] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.294889] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.309973] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.324083] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.339426] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.360031] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.376051] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.403210] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.420511] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.435453] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.451268] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.466975] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.652955] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.668239] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.683249] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.701076] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.716683] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.732232] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.770932] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.786965] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.803343] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.819641] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.836300] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.854138] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.869526] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.909804] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1390.991867] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.013328] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.222881] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.237082] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.253525] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.270034] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.286802] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.308689] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.328906] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.344145] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.359650] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.395108] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.415700] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.432114] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.453984] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.473663] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.490308] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.509694] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.531997] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.551925] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.588619] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.805629] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.843270] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.858901] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.874136] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.893810] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.909894] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.926468] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.945909] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.960066] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.976925] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1391.994247] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.032328] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.056519] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.178057] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.192055] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.208735] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.226736] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.243072] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.257858] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.273662] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.289460] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.330830] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.349856] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.369454] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.387775] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.406850] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.425828] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.442510] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.460363] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.619097] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.634494] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.649086] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.665366] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.680195] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.695414] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.749813] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.787382] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.802558] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.821530] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.836361] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.870475] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.947493] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.963125] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.977543] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1392.992664] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.007548] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.063631] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.083959] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.099290] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.117787] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.134062] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.160447] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.179314] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.193774] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.208539] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.226659] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.242200] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.301852] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.322989] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.343556] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.360021] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.382340] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.402110] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.415962] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.434036] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.448285] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.464358] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.698985] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.715026] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.731321] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.746853] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.783478] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.800579] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.822455] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.843108] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.861745] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.878770] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.893236] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.910017] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.926608] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.944099] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.963022] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1393.996992] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.022019] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.111206] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.125695] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.142087] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.293159] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.313915] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.359283] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.476173] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.494514] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.626707] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.675255] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.693924] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.710328] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.728987] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.743043] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.757942] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.794377] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.810601] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.828685] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.848685] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.864617] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.885318] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.907625] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.923282] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.947517] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.965275] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1394.984385] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.046364] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.064338] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.080376] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.097483] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.136857] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.155065] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.169718] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.187136] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.202856] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.218859] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.237966] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.300330] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.317534] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.336069] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.362699] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.390928] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.415168] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.444145] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.474645] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.604309] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.661701] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.728054] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.746983] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.766097] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.787928] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.805878] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.826025] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.848466] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.902969] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.927480] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1395.977069] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.030967] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.047581] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.070607] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.089358] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.106401] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.207609] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.226504] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.247487] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.289471] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.314223] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.336693] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.361457] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.453267] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.469694] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.488137] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.505303] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.523446] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.543842] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.572351] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.589767] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.607627] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.625115] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.645722] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.685892] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.822117] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.842706] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.896570] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1396.964576] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.008843] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.053683] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.080428] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.111699] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.154593] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.183349] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.216227] Lustre: lustre-OST0001-osc-ffff8de4435df800: disconnect after 21s idle [ 1397.228777] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.252323] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.276928] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.316513] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.377046] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.417654] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.458334] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.478188] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.526638] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.647409] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.693970] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.754708] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.806192] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.833321] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.877181] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.901197] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1397.986082] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.012515] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.057511] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.083892] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.106885] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.190838] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.208233] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.226176] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.246257] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.262657] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.282836] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.299125] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.344966] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.489995] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.507210] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.523259] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.542048] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.559759] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.579475] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.595792] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.614959] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.631742] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.653835] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.674116] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.758279] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.776716] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.819978] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1398.839841] rw_seq_cst_vs_d (33073): drop_caches: 3 [ 1401.232567] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 09:51:52 (1781617912) [ 1403.435158] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 09:51:54 (1781617914) [ 1405.773809] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 09:51:56 (1781617916) [ 1413.869316] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 09:52:04 (1781617924) [ 1414.037615] bash (35556): drop_caches: 3 [ 1417.187028] bash (35556): drop_caches: 3 [ 1420.393239] bash (35556): drop_caches: 3 [ 1423.896857] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 09:52:15 (1781617935) [ 1428.631985] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 09:52:19 (1781617939) [ 1439.881442] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 09:52:31 (1781617951) [ 1442.631148] Lustre: DEBUG MARKER: loop 5 [ 1444.373501] Lustre: DEBUG MARKER: loop 10 [ 1446.016000] Lustre: DEBUG MARKER: loop 15 [ 1447.683972] Lustre: DEBUG MARKER: loop 20 [ 1450.414932] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 09:52:41 (1781617961) [ 1452.650724] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 09:52:43 (1781617963) [ 1454.935877] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 09:52:46 (1781617966) [ 1458.656206] Lustre: lustre-OST0000-osc-ffff8de4435df800: disconnect after 23s idle [ 1474.016247] Lustre: lustre-OST0001-osc-ffff8de447df8800: disconnect after 23s idle [ 1518.503080] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 09:53:49 (1781618029) [ 1520.534751] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 09:53:51 (1781618031) [ 1522.581681] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 09:53:53 (1781618033) [ 1524.758308] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 09:53:55 (1781618035) [ 1526.954608] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 09:53:58 (1781618038) [ 1530.050245] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 09:54:01 (1781618041) [ 1534.214067] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 09:54:05 (1781618045) [ 1537.353742] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1537.899181] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 09:54:09 (1781618049) [ 1542.095880] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 09:54:13 (1781618053) [ 1542.209717] Lustre: *** cfs_fail_loc=314, val=0*** [ 1543.264210] Lustre: *** cfs_fail_loc=314, val=0*** [ 1543.266277] Lustre: Skipped 2 previous similar messages [ 1545.346198] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 09:54:16 (1781618056) [ 1550.537811] Lustre: *** cfs_fail_loc=314, val=0*** [ 1550.561877] LustreError: lustre-OST0000-osc-ffff8de4435df800: operation ldlm_enqueue to node 192.168.204.101@tcp failed: rc = -107 [ 1550.565942] Lustre: lustre-OST0000-osc-ffff8de4435df800: Connection to lustre-OST0000 (at 192.168.204.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1550.572231] LustreError: lustre-OST0000-osc-ffff8de4435df800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1550.576171] LustreError: 46536:0:(ldlm_resource.c:1180:ldlm_resource_complain()) lustre-OST0000-osc-ffff8de4435df800: namespace resource [0x280000401:0x38:0x0].0x0 (ffff8de4425a4400) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1550.581389] Lustre: lustre-OST0000-osc-ffff8de4435df800: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 1553.025647] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 09:54:24 (1781618064) [ 1553.103643] LustreError: 47125:0:(file.c:818:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1556.120181] LustreError: 47125:0:(file.c:818:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1558.155919] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 09:54:29 (1781618069) [ 1560.932451] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 09:54:32 (1781618072) [ 1563.890066] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1564.403735] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 09:54:35 (1781618075) [ 1564.934266] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1565.440532] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 09:54:36 (1781618076) [ 1566.302272] Lustre: lustre-MDT0000-mdc-ffff8de447df8800: Connection to lustre-MDT0000 (at 192.168.204.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1571.298229] LustreError: MGC192.168.204.101@tcp: Connection to MGS (at 192.168.204.101@tcp) was lost; in progress operations using this service will fail [ 1571.303922] Lustre: Evicted from MGS (at 192.168.204.101@tcp) after server handle changed from 0x267f00497d17727 to 0x267f00497e18745 [ 1571.307716] Lustre: MGC192.168.204.101@tcp: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 1574.246088] Lustre: lustre-MDT0000-mdc-ffff8de4435df800: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 1584.685413] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 09:54:55 (1781618095) [ 1602.017235] Lustre: lustre-OST0000-osc-ffff8de4435df800: disconnect after 20s idle [ 1602.019895] Lustre: Skipped 2 previous similar messages [ 1602.667875] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 09:55:13 (1781618113) [ 1607.907040] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 09:55:19 (1781618119) [ 1657.287131] Lustre: lustre-OST0000-osc-ffff8de447df8800: Connection to lustre-OST0000 (at 192.168.204.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1657.302676] Lustre: Skipped 1 previous similar message [ 1657.312078] LustreError: lustre-OST0000-osc-ffff8de447df8800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1657.322642] Lustre: lustre-OST0000-osc-ffff8de447df8800: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 1657.328230] Lustre: Skipped 1 previous similar message [ 1657.363181] LustreError: lustre-OST0000-osc-ffff8de4435df800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1672.642362] Lustre: lustre-OST0001-osc-ffff8de447df8800: Connection to lustre-OST0001 (at 192.168.204.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1672.654559] Lustre: Skipped 1 previous similar message [ 1672.671383] LustreError: lustre-OST0001-osc-ffff8de447df8800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1672.686480] Lustre: lustre-OST0001-osc-ffff8de447df8800: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 1672.697475] Lustre: Skipped 1 previous similar message [ 1678.817177] Lustre: lustre-OST0000-osc-ffff8de447df8800: disconnect after 22s idle [ 1692.497755] Lustre: DEBUG MARKER: oleg401-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8de4435df800.ost_server_uuid 50 [ 1694.725306] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8de4435df800.ost_server_uuid in IDLE state after 0 sec [ 1701.335297] Lustre: DEBUG MARKER: oleg401-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8de4435df800.ost_server_uuid 50 [ 1702.727500] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8de4435df800.ost_server_uuid in FULL state after 0 sec [ 1708.073860] Lustre: DEBUG MARKER: oleg401-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8de4435df800.ost_server_uuid 50 [ 1709.211688] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8de4435df800.ost_server_uuid in IDLE state after 0 sec [ 1712.776884] Lustre: DEBUG MARKER: oleg401-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8de4435df800.ost_server_uuid 50 [ 1713.800952] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8de4435df800.ost_server_uuid in FULL state after 0 sec [ 1720.901508] Lustre: DEBUG MARKER: oleg401-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8de4435df800.ost_server_uuid 50 [ 1721.796181] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8de4435df800.ost_server_uuid in IDLE state after 0 sec [ 1725.129660] Lustre: DEBUG MARKER: oleg401-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8de4435df800.ost_server_uuid 50 [ 1725.990201] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8de4435df800.ost_server_uuid in FULL state after 0 sec [ 1726.894350] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 09:57:17 (1781618237) [ 1728.460202] Lustre: DEBUG MARKER: Race attempt 0 [ 1730.240169] Lustre: DEBUG MARKER: Wait for 58571 58683 for 60 sec... [ 1794.079180] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 09:58:24 (1781618304) [ 1800.209414] Lustre: DEBUG MARKER: start test - cycle (0) [ 1817.379328] Lustre: DEBUG MARKER: start test - cycle (1) [ 1837.313361] Lustre: DEBUG MARKER: start test - cycle (2) [ 1842.658864] Lustre: lustre-OST0000-osc-ffff8de4435df800: disconnect after 20s idle [ 1842.662079] Lustre: Skipped 2 previous similar messages [ 1856.712247] Lustre: DEBUG MARKER: start test - cycle (3) [ 1872.716480] Lustre: DEBUG MARKER: start test - cycle (4) [ 1893.003552] Lustre: DEBUG MARKER: start test - cycle (5) [ 1915.178987] Lustre: DEBUG MARKER: start test - cycle (6) [ 1919.458366] Lustre: lustre-OST0000-osc-ffff8de4435df800: disconnect after 21s idle [ 1933.539736] Lustre: DEBUG MARKER: start test - cycle (7) [ 1953.991783] Lustre: DEBUG MARKER: start test - cycle (8) [ 1979.019712] Lustre: DEBUG MARKER: start test - cycle (9) [ 2003.443693] Lustre: DEBUG MARKER: start test - cycle (10) [ 2037.018829] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 10:02:26 (1781618546) [ 2053.088200] Lustre: lustre-OST0000-osc-ffff8de447df8800: disconnect after 24s idle [ 2053.090987] Lustre: Skipped 3 previous similar messages [ 2112.380646] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 10:03:42 (1781618622) [ 2119.169160] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 10:03:49 (1781618629) [ 2126.548706] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 10:03:56 (1781618636) [ 2133.683517] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 10:04:04 (1781618644) [ 2134.156037] Lustre: *** cfs_fail_loc=411, val=0*** [ 2139.934372] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2141.227988] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 10:04:11 (1781618651) [ 2156.948939] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 10:04:27 (1781618667) [ 2172.499902] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 10:04:42 (1781618682) [ 2186.943786] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 10:04:57 (1781618697) [ 2200.479971] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 10:05:11 (1781618711) [ 2211.956400] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 10:05:22 (1781618722) [ 2224.194322] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 10:05:34 (1781618734) [ 2236.619451] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 10:05:46 (1781618746) [ 2248.000802] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 10:05:58 (1781618758) [ 2259.553332] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 10:06:09 (1781618769) [ 2271.467920] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 10:06:21 (1781618781) [ 2295.607842] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 10:06:45 (1781618805) [ 2310.915316] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 10:07:00 (1781618820) [ 2314.208236] Lustre: lustre-OST0001-osc-ffff8de447df8800: disconnect after 24s idle [ 2314.211566] Lustre: Skipped 8 previous similar messages [ 2928.608834] Lustre: lustre-OST0000-osc-ffff8de4435df800: disconnect after 24s idle [ 2928.615584] Lustre: Skipped 1 previous similar message [ 3345.452337] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 10:24:15 (1781619855) [ 3356.653311] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 10:24:27 (1781619867) [ 3367.866930] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 10:24:38 (1781619878) [ 3378.253454] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 10:24:48 (1781619888) [ 3388.628285] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 10:24:59 (1781619899) [ 3398.079833] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 10:25:08 (1781619908) [ 3407.656901] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 10:25:18 (1781619918) [ 3416.506203] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 10:25:27 (1781619927) [ 3426.443425] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 10:25:37 (1781619937) [ 3502.916893] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 10:26:53 (1781620013) [ 3512.119353] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 10:27:02 (1781620022) [ 3520.929505] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 10:27:11 (1781620031) [ 3529.843928] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 10:27:20 (1781620040) [ 3539.094573] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 10:27:29 (1781620049) [ 3548.708813] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 10:27:39 (1781620059) [ 3558.440315] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 10:27:48 (1781620068) [ 3568.183484] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 10:27:58 (1781620078) [ 3577.383550] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 10:28:08 (1781620088) [ 3589.088322] Lustre: lustre-OST0001-osc-ffff8de447df8800: disconnect after 20s idle [ 3589.098083] Lustre: Skipped 4 previous similar messages [ 3657.988766] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 10:29:28 (1781620168) [ 4402.356468] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 10:41:53 (1781620913) [ 4409.108676] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 10:41:59 (1781620919) [ 4413.408315] Lustre: lustre-OST0001-osc-ffff8de4435df800: disconnect after 24s idle [ 4413.411684] Lustre: Skipped 2 previous similar messages [ 4416.241149] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 10:42:07 (1781620927) [ 4423.317095] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 10:42:14 (1781620934) [ 4430.436117] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 10:42:21 (1781620941) [ 4441.297464] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 10:42:31 (1781620951) [ 4456.454842] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 10:42:46 (1781620966) [ 4472.336745] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 10:43:02 (1781620982) [ 4488.404255] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 10:43:18 (1781620998) [ 4502.467274] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 10:43:32 (1781621012) [ 4655.545484] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 10:46:05 (1781621165) [ 4666.722042] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 10:46:17 (1781621177) [ 4677.880951] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 10:46:28 (1781621188) [ 4688.857470] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 10:46:39 (1781621199) [ 4699.674227] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 10:46:50 (1781621210) [ 4711.381558] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 10:47:01 (1781621221) [ 4721.826283] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 10:47:12 (1781621232) [ 4732.382865] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 10:47:22 (1781621242) [ 4742.358238] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 10:47:32 (1781621252) [ 5384.673903] Lustre: lustre-OST0001-osc-ffff8de447df8800: disconnect after 23s idle [ 5384.683399] Lustre: Skipped 8 previous similar messages [ 5511.757306] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 11:00:22 (1781622022) [ 5520.827526] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 11:00:31 (1781622031) [ 5529.509281] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 11:00:40 (1781622040) [ 5538.000475] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 11:00:48 (1781622048) [ 5547.278229] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 11:00:57 (1781622057) [ 5556.390740] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 11:01:07 (1781622067) [ 5565.631845] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 11:01:16 (1781622076) [ 5574.802653] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 11:01:25 (1781622085) [ 5584.165286] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 11:01:34 (1781622094) [ 5593.481245] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 11:01:44 (1781622104) [ 5602.187563] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 11:01:52 (1781622112) [ 5612.063721] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 11:02:02 (1781622122) [ 5621.023149] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 11:02:11 (1781622131) [ 5629.605668] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 11:02:20 (1781622140) [ 5638.323476] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 11:02:29 (1781622149) [ 5647.209017] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 11:02:37 (1781622157) [ 5658.176506] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 11:02:48 (1781622168) [ 5658.354454] LustreError: 19715:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5660.440189] LustreError: 19715:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 5667.637155] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 11:02:58 (1781622178) [ 5673.794623] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 11:03:04 (1781622184) [ 5674.039320] LustreError: 240263:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5678.120131] LustreError: 240263:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 5678.141083] LustreError: 240263:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5682.200717] LustreError: 240263:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 5682.283510] LustreError: 240270:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5686.352118] LustreError: 240270:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 5693.522833] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 11:03:23 (1781622203) [ 5709.394365] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 11:03:37 (1781622217) [ 5722.849953] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 11:03:51 (1781622231) [ 5738.722421] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 11:04:07 (1781622247) [ 5774.528866] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 11:04:44 (1781622284) [ 5787.007238] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 11:04:57 (1781622297) [ 5799.437099] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 11:05:09 (1781622309) [ 5817.534517] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 11:05:27 (1781622327) [ 5832.094147] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 11:05:42 (1781622342) [ 5851.088971] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 11:06:01 (1781622361) [ 5869.746871] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 11:06:19 (1781622379) [ 5891.542983] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 11:06:41 (1781622401) [ 5914.872070] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 11:07:05 (1781622425) [ 6005.295193] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 11:08:35 (1781622515) [ 6013.635156] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 6019.825323] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 11:08:49 (1781622529) [ 6027.328835] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 11:08:57 (1781622537) [ 6033.763853] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 11:09:04 (1781622544) [ 6038.931652] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 11:09:09 (1781622549) [ 6082.753270] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 11:09:52 (1781622592) [ 6144.974976] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 11:10:55 (1781622655) [ 6151.862602] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 11:11:01 (1781622661) [ 6159.300261] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 11:11:09 (1781622669) [ 6162.598941] LustreError: lustre-MDT0000-mdc-ffff8de4435df800: operation ldlm_enqueue to node 192.168.204.101@tcp failed: rc = -35 [ 6169.709300] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 11:11:19 (1781622679) [ 6170.287463] LustreError: 2402:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 6172.376662] LustreError: 2402:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 6182.417168] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 11:11:32 (1781622692) [ 6193.632435] Lustre: lustre-OST0000-osc-ffff8de4435df800: disconnect after 24s idle [ 6193.651561] Lustre: Skipped 7 previous similar messages [ 6344.542936] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 11:14:14 (1781622854) [ 6352.902229] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 11:14:23 (1781622863) [ 6364.708054] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 11:14:35 (1781622875) [ 6378.897627] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 11:14:49 (1781622889) [ 6392.303452] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 11:15:02 (1781622902) [ 6410.987945] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 11:15:21 (1781622921) [ 6431.124558] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 11:15:41 (1781622941) [ 6440.496919] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 11:15:50 (1781622950) [ 6451.425665] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 11:16:01 (1781622961) [ 6471.005134] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 11:16:21 (1781622981) [ 6522.668372] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 11:17:13 (1781623033) [ 6648.991386] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 11:19:19 (1781623159) [ 6776.510910] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 11:21:27 (1781623287) [ 6802.912725] Lustre: lustre-OST0001-osc-ffff8de447df8800: disconnect after 20s idle [ 6802.919109] Lustre: Skipped 13 previous similar messages [ 7078.378573] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 11:26:29 (1781623589) [ 7109.301420] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 11:27:00 (1781623620) [ 7140.398142] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 11:27:31 (1781623651) [ 7171.640797] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 11:28:02 (1781623682) [ 7232.147486] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 11:29:03 (1781623743) [ 7288.171172] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 11:29:59 (1781623799) [ 7385.348731] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 11:31:36 (1781623896) [ 7387.459622] Lustre: Unmounted lustre-client [ 7389.248137] Lustre: Unmounted lustre-client [ 7463.938467] Lustre: Mounted lustre-client [ 7465.834495] Lustre: Mounted lustre-client [ 7467.635946] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7486.432790] Lustre: lustre-OST0000-osc-ffff8de481159800: disconnect after 21s idle [ 7486.437456] Lustre: Skipped 12 previous similar messages [ 7537.184117] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 11:34:08 (1781624048) [ 7546.673489] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 11:34:17 (1781624057) [ 7551.428734] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 11:34:22 (1781624062) [ 7602.979290] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 11:35:14 (1781624114) [ 7652.849834] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 11:36:03 (1781624163) [ 7657.260439] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 11:36:08 (1781624168) [ 7700.426907] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 11:36:51 (1781624211) [ 7713.702634] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 11:37:04 (1781624224) [ 7755.019860] Lustre: DEBUG MARKER: == sanityn test 77s: Check TBF LRU shrinker ============== 11:37:46 (1781624266) [ 7774.881907] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 11:38:05 (1781624285) [ 7778.584603] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 11:38:09 (1781624289) [ 7792.578266] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 11:38:23 (1781624303) [ 7797.906995] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 11:38:28 (1781624308) [ 7798.631677] LustreError: 309364:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x721:0x0]: rc = -5 [ 7798.638973] LustreError: 309364:0:(llite_lib.c:3749:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7799.203259] LustreError: 309385:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x2a:0x0]: rc = -5 [ 7799.207576] LustreError: 309385:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 3 previous similar messages [ 7799.213048] LustreError: 309385:0:(llite_lib.c:3749:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7799.219549] LustreError: 309385:0:(llite_lib.c:3749:ll_prep_inode()) Skipped 3 previous similar messages [ 7800.369127] LustreError: 309436:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x73f:0x0]: rc = -5 [ 7800.374488] LustreError: 309436:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 11 previous similar messages [ 7800.378692] LustreError: 309436:0:(llite_lib.c:3749:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7800.383536] LustreError: 309436:0:(llite_lib.c:3749:ll_prep_inode()) Skipped 11 previous similar messages [ 7802.460230] LustreError: 309525:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x75f:0x0]: rc = -5 [ 7802.464742] LustreError: 309525:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 26 previous similar messages [ 7802.468890] LustreError: 309525:0:(llite_lib.c:3749:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7802.474942] LustreError: 309525:0:(llite_lib.c:3749:ll_prep_inode()) Skipped 26 previous similar messages [ 7806.515614] LustreError: 309717:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x7a:0x0]: rc = -5 [ 7806.521902] LustreError: 309717:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 56 previous similar messages [ 7806.525548] LustreError: 309717:0:(llite_lib.c:3749:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7806.530266] LustreError: 309717:0:(llite_lib.c:3749:ll_prep_inode()) Skipped 56 previous similar messages [ 7814.767304] LustreError: 310050:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x7f4:0x0]: rc = -5 [ 7814.775975] LustreError: 310050:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 98 previous similar messages [ 7814.780461] LustreError: 310050:0:(llite_lib.c:3749:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7814.787154] LustreError: 310050:0:(llite_lib.c:3749:ll_prep_inode()) Skipped 98 previous similar messages [ 7830.771760] LustreError: 309204:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x1c3:0x0]: rc = -5 [ 7830.778059] LustreError: 309204:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 265 previous similar messages [ 7830.782729] LustreError: 309204:0:(llite_lib.c:3749:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7830.786356] LustreError: 309204:0:(llite_lib.c:3749:ll_prep_inode()) Skipped 265 previous similar messages [ 7853.803437] LustreError: 311992:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8de481159800: inode [0x2000013a1:0xa83:0x0] mdc close failed: rc = -2 [ 7868.985356] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 11:39:39 (1781624379) [ 7872.095875] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 11:39:43 (1781624383) [ 7957.707727] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 11:41:08 (1781624468) [ 7958.447488] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 7959.194733] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 11:41:10 (1781624470) [ 8038.554511] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 11:42:29 (1781624549) [ 8041.329298] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 11:42:32 (1781624552) [ 8164.187844] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 11:44:35 (1781624675) [ 8171.822735] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 11:44:42 (1781624682) [ 8175.125301] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 11:44:46 (1781624686) [ 8359.671155] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 11:47:50 (1781624870) [ 8423.392207] Lustre: lustre-OST0000-osc-ffff8de4805b8800: disconnect after 23s idle [ 8423.396095] Lustre: Skipped 11 previous similar messages [ 8542.502859] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 11:50:53 (1781625053) [ 8545.155380] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 11:50:56 (1781625056) [ 8554.757864] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 11:51:05 (1781625065) [ 8554.808554] Lustre: DEBUG MARKER: write [ 8554.830948] LustreError: 288700:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 8556.835895] Lustre: DEBUG MARKER: kill 359860 [ 8556.837807] LustreError: 359860:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 8559.928134] LustreError: 288700:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 8562.872122] LustreError: 359860:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 8565.478138] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 11:51:16 (1781625076) [ 8567.737044] LustreError: 360474:0:(rw.c:1865:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 8577.832127] LustreError: 360474:0:(rw.c:1865:ll_readpage()) cfs_fail_timeout id 1422 awake [ 8580.375844] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 11:51:31 (1781625091) [ 8580.487576] LustreError: 361062:0:(rw.c:2112:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 8582.568129] LustreError: 361062:0:(rw.c:2112:ll_readpage()) cfs_fail_timeout interrupted [ 8589.123211] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 11:51:40 (1781625100) [ 8589.754297] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 8590.482751] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 11:51:41 (1781625101) [ 8593.279719] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 11:51:44 (1781625104) [ 8595.783940] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 11:51:46 (1781625106) [ 8598.265527] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 11:51:49 (1781625109) [ 8600.982444] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 11:51:52 (1781625112) [ 8603.444282] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 11:51:54 (1781625114) [ 8606.042966] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 11:51:57 (1781625117) [ 8609.475648] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 11:52:00 (1781625120) [ 8612.814510] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 11:52:03 (1781625123) [ 8613.531146] Lustre: *** cfs_fail_loc=415, val=0*** [ 8620.761370] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 11:52:11 (1781625131) [ 8640.698574] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 11:52:31 (1781625151) [ 8640.820745] LustreError: 288699:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 8640.826219] LustreError: 288699:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 8645.824132] LustreError: 288699:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 8656.024138] LustreError: 288699:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 8656.029593] LustreError: 288699:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 6 previous similar messages [ 8663.582176] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 11:52:54 (1781625174) [ 8666.134633] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 11:52:57 (1781625177) [ 8668.651403] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 11:52:59 (1781625179) [ 8671.022428] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 11:53:02 (1781625182) [ 8675.911082] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 11:53:06 (1781625186) [ 8684.871414] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 11:53:15 (1781625195) [ 8685.121663] LustreError: 371794:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 8685.124633] LustreError: 371794:0:(osc_request.c:2989:osc_build_rpc()) Skipped 6 previous similar messages [ 8689.184328] LustreError: 371794:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 8689.188723] LustreError: 371794:0:(osc_request.c:2989:osc_build_rpc()) Skipped 2 previous similar messages [ 8691.706182] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 11:53:22 (1781625202) [ 8693.242829] Lustre: Unmounted lustre-client [ 8693.941059] Lustre: Unmounted lustre-client [ 8694.498277] Lustre: DEBUG MARKER: Iteration 0 [ 8694.617741] LustreError: 372686:0:(llite_lib.c:1385:ll_fill_super()) cfs_race id 1417 sleeping [ 8694.622553] LustreError: 372687:0:(llite_lib.c:1385:ll_fill_super()) cfs_fail_race id 1417 waking [ 8694.626109] LustreError: 372686:0:(llite_lib.c:1385:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8694.679624] Lustre: Mounted lustre-client [ 8694.680934] Lustre: Skipped 1 previous similar message [ 8695.230878] Lustre: Unmounted lustre-client [ 8696.389137] Key type lgssc unregistered [ 8696.522415] LNet: 373029:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8696.526452] LNetError: 373029:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8696.533769] LNet: Removed LNI 192.168.204.1@tcp [ 8696.864136] Key type .llcrypt unregistered [ 8696.865593] Key type ._llcrypt unregistered [ 8697.198461] Key type ._llcrypt registered [ 8697.200460] Key type .llcrypt registered [ 8697.461074] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8697.468247] alg: No test for adler32 (adler32-zlib) [ 8698.487389] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 8698.808181] LNet: Added LNI 192.168.204.1@tcp [8/256/0/180] [ 8700.440162] Key type lgssc registered [ 8701.053115] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8705.575647] Lustre: DEBUG MARKER: Iteration 1 [ 8705.713297] LustreError: 373872:0:(llite_lib.c:1385:ll_fill_super()) cfs_race id 1417 sleeping [ 8705.713331] LustreError: 373873:0:(llite_lib.c:1385:ll_fill_super()) cfs_fail_race id 1417 waking [ 8705.721370] LustreError: 373872:0:(llite_lib.c:1385:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8706.804865] Lustre: Mounted lustre-client [ 8707.359160] Lustre: Unmounted lustre-client [ 8707.360401] Lustre: Skipped 1 previous similar message [ 8708.403225] Key type lgssc unregistered [ 8708.528702] LNet: 374216:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8708.532859] LNetError: 374216:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8708.540639] LNet: Removed LNI 192.168.204.1@tcp [ 8708.789160] Key type .llcrypt unregistered [ 8708.790615] Key type ._llcrypt unregistered [ 8709.123139] Key type ._llcrypt registered [ 8709.124634] Key type .llcrypt registered [ 8709.333569] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8709.339831] alg: No test for adler32 (adler32-zlib) [ 8710.212690] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 8710.302395] LNet: Added LNI 192.168.204.1@tcp [8/256/0/180] [ 8711.888161] Key type lgssc registered [ 8712.285874] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8715.693206] Lustre: DEBUG MARKER: Iteration 2 [ 8715.790330] LustreError: 375048:0:(llite_lib.c:1385:ll_fill_super()) cfs_race id 1417 sleeping [ 8715.790370] LustreError: 375049:0:(llite_lib.c:1385:ll_fill_super()) cfs_fail_race id 1417 waking [ 8715.795096] LustreError: 375048:0:(llite_lib.c:1385:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8716.847474] Lustre: Mounted lustre-client [ 8716.848840] Lustre: Skipped 1 previous similar message [ 8717.376134] Lustre: Unmounted lustre-client [ 8717.378428] Lustre: Skipped 1 previous similar message [ 8718.291340] Key type lgssc unregistered [ 8718.405499] LNet: 375395:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8718.407538] LNetError: 375395:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8718.416672] LNet: Removed LNI 192.168.204.1@tcp [ 8718.653128] Key type .llcrypt unregistered [ 8718.654827] Key type ._llcrypt unregistered [ 8718.917400] Key type ._llcrypt registered [ 8718.925829] Key type .llcrypt registered [ 8719.093532] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8719.102468] alg: No test for adler32 (adler32-zlib) [ 8719.973218] Lustre: Lustre: Build Version: 2.17.54_40_g386d0a3 [ 8720.062326] LNet: Added LNI 192.168.204.1@tcp [8/256/0/180] [ 8721.648161] Key type lgssc registered [ 8722.078788] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8726.530784] Lustre: Mounted lustre-client [ 8728.943853] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 11:54:00 (1781625240) [ 8744.416095] Lustre: 376748:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781625240/real 1781625240] req@ffff8de446643800 x1868169458231168/t0(0) o36->lustre-MDT0000-mdc-ffff8de44afb4000@192.168.204.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1781625256 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8744.424966] Lustre: lustre-MDT0000-mdc-ffff8de44afb4000: Connection to lustre-MDT0000 (at 192.168.204.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8744.435231] Lustre: lustre-MDT0000-mdc-ffff8de44afb4000: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 8760.800262] Lustre: 376748:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781625256/real 1781625256] req@ffff8de446643800 x1868169458231168/t0(0) o36->lustre-MDT0000-mdc-ffff8de44afb4000@192.168.204.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1781625272 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8760.809450] Lustre: lustre-MDT0000-mdc-ffff8de44afb4000: Connection to lustre-MDT0000 (at 192.168.204.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8760.822923] Lustre: lustre-MDT0000-mdc-ffff8de44afb4000: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 8777.184281] Lustre: 376748:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781625272/real 1781625272] req@ffff8de446643800 x1868169458231168/t0(0) o36->lustre-MDT0000-mdc-ffff8de44afb4000@192.168.204.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1781625288 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8777.197548] Lustre: lustre-MDT0000-mdc-ffff8de44afb4000: Connection to lustre-MDT0000 (at 192.168.204.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8777.212483] Lustre: lustre-MDT0000-mdc-ffff8de44afb4000: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 8792.544147] Lustre: 376748:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781625288/real 1781625288] req@ffff8de446643800 x1868169458231168/t0(0) o36->lustre-MDT0000-mdc-ffff8de44afb4000@192.168.204.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1781625304 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8792.554523] Lustre: lustre-MDT0000-mdc-ffff8de44afb4000: Connection to lustre-MDT0000 (at 192.168.204.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8792.563567] Lustre: lustre-MDT0000-mdc-ffff8de44afb4000: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 8808.928604] Lustre: 376748:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781625304/real 1781625304] req@ffff8de446643800 x1868169458231168/t0(0) o36->lustre-MDT0000-mdc-ffff8de44afb4000@192.168.204.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1781625320 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8808.942584] Lustre: lustre-MDT0000-mdc-ffff8de44afb4000: Connection to lustre-MDT0000 (at 192.168.204.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8808.958673] Lustre: lustre-MDT0000-mdc-ffff8de44afb4000: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 8809.557906] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 11:55:20 (1781625320) [ 8815.666471] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 11:55:26 (1781625326) [ 8819.231801] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 11:55:30 (1781625330) [ 8821.446832] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 11:55:32 (1781625332) [ 8828.858033] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 11:55:39 (1781625339) [ 8842.457509] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 11:55:53 (1781625353) [ 8844.971875] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 11:55:56 (1781625356) [ 8845.057654] LustreError: 381525:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 8847.144135] LustreError: 381525:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 8849.618085] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 11:56:00 (1781625360) [ 8868.320236] Lustre: 375599:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781625363/real 1781625363] req@ffff8de47dd54a80 x1868169459272320/t0(0) o4->lustre-OST0000-osc-ffff8de44afb4000@192.168.204.101@tcp:6/4 lens 4584/448 e 0 to 1 dl 1781625379 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8868.320276] Lustre: lustre-OST0000-osc-ffff8de44afb4000: Connection to lustre-OST0000 (at 192.168.204.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8868.330727] Lustre: 375599:0:(client.c:2489:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 8868.344159] Lustre: lustre-OST0000-osc-ffff8de44afb4000: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 8900.064214] Lustre: 375599:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781625395/real 1781625395] req@ffff8de47dd54a80 x1868169459272320/t0(0) o4->lustre-OST0000-osc-ffff8de44afb4000@192.168.204.101@tcp:6/4 lens 4584/448 e 0 to 1 dl 1781625411 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8900.064317] Lustre: lustre-OST0000-osc-ffff8de44afb4000: Connection to lustre-OST0000 (at 192.168.204.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8900.075625] Lustre: 375599:0:(client.c:2489:ptlrpc_expire_one_request()) Skipped 9 previous similar messages [ 8900.084918] Lustre: Skipped 1 previous similar message [ 8900.096613] Lustre: lustre-OST0000-osc-ffff8de44afb4000: Connection restored to 192.168.204.101@tcp (at 192.168.204.101@tcp) [ 8900.101144] Lustre: Skipped 1 previous similar message [ 8939.700774] Lustre: DEBUG MARKER: oleg401-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8de44afb4000.ost_server_uuid 50 [ 8940.254510] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8de44afb4000.ost_server_uuid in FULL state after 0 sec [ 8940.855309] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 8941.503130] Lustre: DEBUG MARKER: == sanityn test complete, duration 8729 sec ============== 11:57:32 (1781625452) [ 8942.124733] Lustre: DEBUG MARKER: === sanityn: start cleanup 11:57:33 (1781625453) === [ 9047.363045] Lustre: Unmounted lustre-client [ 9048.840755] Lustre: DEBUG MARKER: === sanityn: finish cleanup 11:59:19 (1781625559) === [ 9049.218227] Lustre: Unmounted lustre-client [ 9068.796869] Key type lgssc unregistered [ 9068.930800] LNet: 384585:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9068.934718] LNetError: 384585:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9068.945573] LNet: Removed LNI 192.168.204.1@tcp [ 9069.263368] Key type .llcrypt unregistered [ 9069.264887] Key type ._llcrypt unregistered