[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 460891052 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2880952K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.002413] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004009] 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.008018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009008] pid_max: default: 32768 minimum: 301 [ 0.010214] LSM: Security Framework initializing [ 0.011034] Yama: becoming mindful. [ 0.012025] SELinux: Initializing. [ 0.013055] *** VALIDATE selinux *** [ 0.022863] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027520] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029036] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030083] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031088] *** VALIDATE tmpfs *** [ 0.033170] *** VALIDATE proc *** [ 0.034275] *** VALIDATE cgroup *** [ 0.035005] *** VALIDATE cgroup2 *** [ 0.036089] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037141] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039017] Spectre V2 : User space: Vulnerable [ 0.040003] Speculative Store Bypass: Vulnerable [ 0.043114] debug: unmapping init [mem 0xffffffffbd459000-0xffffffffbd460fff] [ 0.046000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046569] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048010] ... version: 2 [ 0.048846] ... bit width: 48 [ 0.049005] ... generic registers: 4 [ 0.050014] ... value mask: 0000ffffffffffff [ 0.051022] ... max period: 00007fffffffffff [ 0.052005] ... fixed-purpose events: 3 [ 0.053019] ... event mask: 000000070000000f [ 0.054293] rcu: Hierarchical SRCU implementation. [ 0.057968] smp: Bringing up secondary CPUs ... [ 0.058567] x86: Booting SMP configuration: [ 0.059016] .... node #0, CPUs: #1 #2 #3 [ 0.071542] smp: Brought up 1 node, 4 CPUs [ 0.073010] smpboot: Max logical packages: 1 [ 0.074012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.118092] node 0 deferred pages initialised in 42ms [ 0.133227] devtmpfs: initialized [ 0.135251] x86/mm: Memory block size: 128MB [ 0.139876] gcov: version magic: 0x41383552 [ 0.142283] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.145065] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.147365] pinctrl core: initialized pinctrl subsystem [ 0.150131] [ 0.150837] ************************************************************* [ 0.153011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.156014] ** ** [ 0.159010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.161008] ** ** [ 0.164010] ** This means that this kernel is built to expose internal ** [ 0.167009] ** IOMMU data structures, which may compromise security on ** [ 0.169008] ** your system. ** [ 0.172009] ** ** [ 0.175008] ** If you see this message and you are not debugging the ** [ 0.176007] ** kernel, report this immediately to your vendor! ** [ 0.179009] ** ** [ 0.182010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.185016] ************************************************************* [ 0.188920] NET: Registered protocol family 16 [ 0.191598] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.195063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.198098] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.202254] cpuidle: using governor menu [ 0.205017] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.208614] PCI: Using configuration type 1 for base access [ 0.211154] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.226245] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.227011] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.230062] cryptd: max_cpu_qlen set to 1000 [ 0.235314] ACPI: Added _OSI(Module Device) [ 0.236012] ACPI: Added _OSI(Processor Device) [ 0.238011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.239008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.246275] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.253575] ACPI: Interpreter enabled [ 0.254100] ACPI: PM: (supports S0 S3 S4 S5) [ 0.255008] ACPI: Using IOAPIC for interrupt routing [ 0.256183] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.259472] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.357298] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.364064] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.370029] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.379154] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.392597] acpiphp: Slot [2] registered [ 0.396531] acpiphp: Slot [5] registered [ 0.399886] acpiphp: Slot [6] registered [ 0.403182] acpiphp: Slot [3] registered [ 0.405824] acpiphp: Slot [4] registered [ 0.407131] acpiphp: Slot [7] registered [ 0.409810] acpiphp: Slot [8] registered [ 0.410000] acpiphp: Slot [9] registered [ 0.411146] acpiphp: Slot [10] registered [ 0.412151] acpiphp: Slot [11] registered [ 0.416808] acpiphp: Slot [12] registered [ 0.420140] acpiphp: Slot [13] registered [ 0.421168] acpiphp: Slot [14] registered [ 0.422074] acpiphp: Slot [15] registered [ 0.423141] acpiphp: Slot [16] registered [ 0.424000] acpiphp: Slot [17] registered [ 0.424000] acpiphp: Slot [18] registered [ 0.426090] acpiphp: Slot [19] registered [ 0.428146] acpiphp: Slot [20] registered [ 0.429083] acpiphp: Slot [21] registered [ 0.430084] acpiphp: Slot [22] registered [ 0.432147] acpiphp: Slot [23] registered [ 0.433174] acpiphp: Slot [24] registered [ 0.434075] acpiphp: Slot [25] registered [ 0.435121] acpiphp: Slot [26] registered [ 0.436081] acpiphp: Slot [27] registered [ 0.437143] acpiphp: Slot [28] registered [ 0.438092] acpiphp: Slot [29] registered [ 0.440133] acpiphp: Slot [30] registered [ 0.442102] acpiphp: Slot [31] registered [ 0.443109] PCI host bridge to bus 0000:00 [ 0.444019] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.445017] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.447021] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.449084] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.451018] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.452024] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.453197] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.456201] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.459561] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.468015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.471000] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.473016] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.475032] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.476009] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.479065] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.503132] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.505036] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.507818] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.511013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.522027] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.537016] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.564254] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.570017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.574012] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.588019] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.600873] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.607017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.616030] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.660020] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.679288] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.681553] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.684562] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.687315] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.689270] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.694142] iommu: Default domain type: Passthrough [ 0.697588] SCSI subsystem initialized [ 0.699131] ACPI: bus type USB registered [ 0.700135] usbcore: registered new interface driver usbfs [ 0.701000] usbcore: registered new interface driver hub [ 0.701000] usbcore: registered new device driver usb [ 0.701000] pps_core: LinuxPPS API ver. 1 registered [ 0.701000] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.701000] PTP clock support registered [ 0.702267] EDAC MC: Ver: 3.0.0 [ 0.703088] PCI: Using ACPI for IRQ routing [ 0.707661] NetLabel: Initializing [ 0.709007] NetLabel: domain hash size = 128 [ 0.710009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.712088] NetLabel: unlabeled traffic allowed by default [ 0.715003] vgaarb: loaded [ 0.716927] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.719016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.725350] clocksource: Switched to clocksource kvm-clock [ 0.838695] VFS: Disk quotas dquot_6.6.0 [ 0.840065] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.842298] *** VALIDATE ramfs *** [ 0.843421] *** VALIDATE hugetlbfs *** [ 0.844913] pnp: PnP ACPI init [ 0.847241] pnp: PnP ACPI: found 6 devices [ 0.863507] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.866635] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.868520] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.872099] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.873708] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.875576] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.878430] NET: Registered protocol family 2 [ 0.882039] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.887560] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.893448] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.901756] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.906717] TCP: Hash tables configured (established 65536 bind 65536) [ 0.911982] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.914132] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.916892] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.919536] NET: Registered protocol family 1 [ 0.922850] RPC: Registered named UNIX socket transport module. [ 0.925344] RPC: Registered udp transport module. [ 0.927053] RPC: Registered tcp transport module. [ 0.928778] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.930927] NET: Registered protocol family 44 [ 0.936693] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.938478] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.940483] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.942439] PCI: CLS 0 bytes, default 64 [ 0.943946] Unpacking initramfs... [ 5.640456] debug: unmapping init [mem 0xffff96adfcc64000-0xffff96adfffcffff] [ 5.644383] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 5.646318] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 5.648874] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 6.737089] hrtimer: interrupt took 6075730 ns [ 7.421530] Initialise system trusted keyrings [ 7.422925] Key type blacklist registered [ 7.424795] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 7.433815] zbud: loaded [ 7.436749] *** VALIDATE nfs *** [ 7.437779] *** VALIDATE nfs4 *** [ 7.439394] pstore: using deflate compression [ 7.443300] Platform Keyring initialized [ 8.040843] NET: Registered protocol family 38 [ 8.066230] Key type asymmetric registered [ 8.067886] Asymmetric key parser 'x509' registered [ 8.100373] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 8.106462] io scheduler mq-deadline registered [ 8.114071] io scheduler kyber registered [ 8.118266] io scheduler bfq registered [ 8.130289] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 8.133262] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 8.139582] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 8.159013] ACPI: Power Button [PWRF] [ 8.189542] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 8.210555] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 8.252074] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 8.336383] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 8.439866] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 8.452649] Non-volatile memory driver v1.3 [ 8.453847] Linux agpgart interface v0.103 [ 8.553479] virtio_blk virtio1: [vda] 134152 512-byte logical blocks (68.7 MB/65.5 MiB) [ 8.563809] vda: detected capacity change from 0 to 68685824 [ 8.589893] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 8.591841] vdb: detected capacity change from 0 to 1073741824 [ 8.600712] libphy: Fixed MDIO Bus: probed [ 8.605927] usbcore: registered new interface driver usbserial_generic [ 8.607715] usbserial: USB Serial support registered for generic [ 8.609579] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 8.613757] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 8.620199] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 8.624946] mousedev: PS/2 mouse device common for all mice [ 8.640460] rtc_cmos 00:05: RTC can wake from S4 [ 8.655770] rtc_cmos 00:05: registered as rtc0 [ 8.657352] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 8.661263] intel_pstate: CPU model not supported [ 8.667717] hid: raw HID events driver (C) Jiri Kosina [ 8.670372] usbcore: registered new interface driver usbhid [ 8.674892] usbhid: USB HID core driver [ 8.677528] drop_monitor: Initializing network drop monitor service [ 8.683419] Initializing XFRM netlink socket [ 8.688150] NET: Registered protocol family 10 [ 8.689799] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 8.695200] Segment Routing with IPv6 [ 8.695235] NET: Registered protocol family 17 [ 8.695640] mpls_gso: MPLS GSO support [ 8.722189] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 8.729913] RAS: Correctable Errors collector initialized. [ 8.732060] AVX version of gcm_enc/dec engaged. [ 8.738974] AES CTR mode by8 optimization enabled [ 8.743405] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 8.901507] sched_clock: Marking stable (8901426580, 0)->(9894456308, -993029728) [ 8.904930] registered taskstats version 1 [ 8.907306] Loading compiled-in X.509 certificates [ 8.909560] zswap: loaded using pool lzo/zbud [ 8.940690] Key type big_key registered [ 8.954758] Key type encrypted registered [ 8.956325] ima: No TPM chip found, activating TPM-bypass! [ 8.958493] ima: Allocated hash algorithm: sha1 [ 8.960217] ima: No architecture policies found [ 8.961619] evm: Initialising EVM extended attributes: [ 8.963746] evm: security.selinux [ 8.965935] evm: security.ima [ 8.966917] evm: security.capability [ 8.968120] evm: HMAC attrs: 0x1 [ 8.972783] rtc_cmos 00:05: setting system clock to 2026-05-08 22:07:34 UTC (1778278054) [ 8.982365] debug: unmapping init [mem 0xffffffffbe403000-0xffffffffbe5fffff] [ 8.986493] debug: unmapping init [mem 0xffffffffbd182000-0xffffffffbd458fff] [ 8.999210] Write protecting the kernel read-only data: 28672k [ 9.004759] debug: unmapping init [mem 0xffffffffbb803000-0xffffffffbb9fffff] [ 9.010536] debug: unmapping init [mem 0xffffffffbc114000-0xffffffffbc1fffff] [ 9.087877] 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) [ 9.100339] systemd[1]: Detected virtualization kvm. [ 9.101584] systemd[1]: Detected architecture x86-64. [ 9.106759] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 9.148924] systemd[1]: No hostname configured. [ 9.150770] systemd[1]: Set hostname to . [ 9.153604] random: systemd: uninitialized urandom read (16 bytes read) [ 9.165121] systemd[1]: Initializing machine ID from random generator. [ 9.415463] random: ln: uninitialized urandom read (6 bytes read) [ 9.658847] random: systemd: uninitialized urandom read (16 bytes read) [ 9.662045] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 9.671562] systemd[1]: Reached target Local Encrypted Volumes. [ OK ] Reached target Local Encrypted Volumes. [ 9.676548] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Swap. Starting Journal Service... Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Setup Virtual Console... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 13.482598] device-mapper: uevent: version 1.0.3 [ 13.485468] 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... [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ 20.188236] virtio_net virtio0 ens2: renamed from eth0 [ 20.960035] random: fast init done [ 21.272072] scsi host0: ata_piix [ 22.166523] scsi host1: ata_piix [ 22.199223] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 22.285068] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 26.797448] random: crng init done [ 26.816388] random: 7 urandom warning(s) missed due to ratelimiting [ 31.711468] dracut-initqueue[585]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 36.403270] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target 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 target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 41.233754] printk: systemd: 26 output lines suppressed due to ratelimiting [ 42.293687] SELinux: Disabled at runtime. [ 42.393326] 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) [ 42.409608] systemd[1]: Detected virtualization kvm. [ 42.411857] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 47.399351] systemd[1]: initrd-switch-root.service: Succeeded. [ 47.414462] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 47.434580] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 47.446943] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 47.469057] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 47.534483] systemd[1]: Starting Journal Service... Starting Journal Service... [ 47.580542] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 47.637744] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [ 47.654955] systemd[1]: Failed to set up automount Arbitrary Executable File Formats File System Automount Point. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ 47.755882] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. Mounting Huge Pages File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting Kernel Debug File System... Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on Process Core Dump Socket. Starting Apply Kernel Variables... [ OK ] Created slice system-getty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target rpc_pipefs.target. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Reached target RPC Port Mapper. [[ 49.626694] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... Starting Create Static Device Nodes in /dev... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 51.201793] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 54.080461] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 54.083981] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 55.951702] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 56.257693] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…only root support (10s / no limit) [** ] A start job is running for Configur…only root support (11s / no limit) [*** ] A start job is running for Configur…only root support (11s / no limit) [ *** ] A start job is running for Configur…only root support (12s / no limit) [ *** ] A start job is running for Configur…only root support (12s / no limit) [ ***] A start job is running for Configur…only root support (13s / no limit) [ **] A start job is running for Configur…only root support (13s / no limit) [ *] A start job is running for Configur…only root support (14s / no limit) [ **] A start job is running for Configur…only root support (14s / no limit) [ ***] A start job is running for Configur…only root support (15s / no limit)[ 62.759352] Key type dns_resolver registered [ *** ] A start job is running for Configur…only root support (15s / no limit) [ *** ] A start job is running for Configur…only root support (16s / no limit) [*** ] A start job is running for Configur…only root support (16s / no limit)[ 64.298243] NFS: Registering the id_resolver key type [ 64.310467] Key type id_resolver registered [ 64.316101] Key type id_legacy registered [** ] A start job is running for Configur…only root support (17s / no limit) [* ] A start job is running for Configur…only root support (17s / no limit) [** ] A start job is running for Configur…only root support (18s / no limit) [ 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 Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ 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 Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg103-client login: [ 154.468908] libcfs: loading out-of-tree module taints kernel. [ 154.558410] Key type ._llcrypt registered [ 154.562564] Key type .llcrypt registered [ 155.196312] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 155.206878] alg: No test for adler32 (adler32-zlib) [ 156.538773] Lustre: Lustre: Build Version: 2.17.52_6_gcfb9284 [ 157.434560] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 159.287192] Key type lgssc registered [ 161.019987] Lustre: Echo OBD driver; http://www.lustre.org/ [ 325.161534] Lustre: Mounted lustre-client [ 330.381788] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 350.693887] Lustre: lustre-OST0000-osc-ffff96ae4ce83000: disconnect after 23s idle [ 352.044632] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing check_logdir /tmp/testlogs/ [ 357.469475] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing yml_node [ 362.284547] Lustre: DEBUG MARKER: Client: 2.17.52.6 [ 364.815840] Lustre: DEBUG MARKER: MDS: 2.17.52.6 [ 367.031745] Lustre: DEBUG MARKER: OSS: 2.17.52.6 [ 368.577456] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri May 8 18:13:32 EDT 2026 [ 385.710207] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 386.994826] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 387.976959] Lustre: DEBUG MARKER: === sanityn: start setup 18:13:52 (1778278432) === [ 388.504795] Lustre: Mounted lustre-client [ 391.435790] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing check_config_client /mnt/lustre [ 409.445072] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 421.647570] Lustre: DEBUG MARKER: === sanityn: finish setup 18:14:26 (1778278466) === [ 424.035553] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 18:14:28 (1778278468) [ 432.217876] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 18:14:36 (1778278476) [ 439.311977] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 18:14:43 (1778278483) [ 445.522417] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 18:14:49 (1778278489) [ 451.858387] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 18:14:56 (1778278496) [ 457.491939] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 18:15:01 (1778278501) [ 463.338706] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 18:15:07 (1778278507) [ 469.752416] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 18:15:14 (1778278514) [ 477.154656] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 18:15:21 (1778278521) [ 483.583799] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 18:15:27 (1778278527) [ 489.851354] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 18:15:34 (1778278534) [ 497.089525] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 18:15:41 (1778278541) [ 501.217225] Lustre: lustre-OST0000-osc-ffff96ae4ce83000: disconnect after 23s idle [ 501.231450] Lustre: Skipped 1 previous similar message [ 503.159546] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 18:15:47 (1778278547) [ 509.369506] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 18:15:53 (1778278553) [ 511.456392] Lustre: lustre-OST0001-osc-ffff96ae4e5e7800: disconnect after 20s idle [ 515.939349] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 18:16:00 (1778278560) [ 521.618673] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 18:16:06 (1778278566) [ 527.425518] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 18:16:11 (1778278571) [ 533.315079] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 18:16:17 (1778278577) [ 539.188252] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 18:16:23 (1778278583) [ 544.172742] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 18:16:28 (1778278588) [ 549.178725] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 18:16:33 (1778278593) [ 549.953375] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 674.482857] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 18:18:39 (1778278719) [ 677.599217] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 18:18:42 (1778278722) [ 680.591677] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 18:18:45 (1778278725) [ 683.773576] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 18:18:48 (1778278728) [ 687.073901] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 18:18:51 (1778278731) [ 690.440729] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 18:18:55 (1778278735) [ 691.460603] Lustre: DEBUG MARKER: chmod [ 694.782136] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 18:18:59 (1778278739) [ 1177.818293] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 18:27:02 (1778279222) [ 1217.906362] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 18:27:43 (1778279263) [ 1237.249434] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 18:28:02 (1778279282) [ 1269.731391] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 18:28:34 (1778279314) [ 1279.479211] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 18:28:44 (1778279324) [ 1281.632637] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 18:28:46 (1778279326) [ 1282.136204] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.161841] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.185787] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.204990] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.226640] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.246065] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.264758] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.286627] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.305558] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.324705] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.345160] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.364112] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.387592] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.412315] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.436607] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.461713] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.485622] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.506414] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.525387] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.542201] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.560760] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.579597] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.597419] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.615730] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.634539] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.651352] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.673334] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.691342] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.713544] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.732037] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.751806] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.771285] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.791232] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.810399] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.830732] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.849688] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.867902] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.886699] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.904393] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.921868] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.939910] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.957829] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.975454] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1282.993633] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.011690] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.030434] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.051018] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.070314] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.089615] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.108704] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.128155] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.146363] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.164137] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.181566] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.200017] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.217859] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.237137] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.254690] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.272316] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.290619] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.308698] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.325331] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.343440] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.362249] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.382200] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.401191] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.421073] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.441744] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.463173] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.485455] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.503932] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.521419] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.539329] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.557425] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.579265] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.597375] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.615588] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.633572] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.651607] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.670204] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.688087] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.710274] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.728160] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.745045] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.762319] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.780403] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.796959] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.818222] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.842079] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.862389] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.880092] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.897829] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.917110] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.937513] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.956638] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.976418] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1283.993558] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.011335] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.035152] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.058569] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.079491] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.100606] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.121681] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.139836] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.162913] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.183403] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.202323] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.222936] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.243190] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.263520] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.282865] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.302865] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.324361] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.347406] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.371290] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.394928] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.416671] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.439603] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.458560] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.476013] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.494193] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.511950] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.530693] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.554445] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.579870] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.603489] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.626768] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.650065] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.672764] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.695746] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.712894] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.730663] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.748434] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.766178] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.783067] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.800178] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.817257] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.834250] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.856779] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.879471] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.897214] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.919306] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.940705] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.964184] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.980649] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1284.997041] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.015754] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.033279] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.051351] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.068601] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.090824] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.114719] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.136563] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.154570] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.172538] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.191600] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.210569] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.227506] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.244827] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.262403] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.282996] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.305765] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.323517] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.340489] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.358136] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.374032] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.389971] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.408380] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.427422] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.444708] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.462765] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.483209] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.500230] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.517559] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.536286] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.556180] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.574381] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.596165] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.614591] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.633982] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.656388] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.678691] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.701341] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.723132] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.742848] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.764941] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.783783] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.800946] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.822704] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.843426] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.859708] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.879802] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.897080] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.915459] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.933544] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.951089] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.969831] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1285.987718] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.005120] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.022541] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.040501] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.058816] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.076428] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.094706] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.111798] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.128611] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.145882] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.162825] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.183161] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.202929] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.220288] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.237664] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.253668] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.271676] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.289765] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.308779] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.329969] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.348329] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.370484] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.388284] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.405227] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.423049] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.440681] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.459188] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.477250] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.494793] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.513518] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.532502] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.551767] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.569103] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.586361] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.605821] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.625840] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.644241] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.661289] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.677660] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.697119] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.714457] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.731810] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.752465] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.773833] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.795828] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.817675] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.837748] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.854985] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.871556] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.888701] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.909393] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.930559] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.949610] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.972480] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1286.993815] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.014360] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.037147] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.060267] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.083754] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.104110] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.122604] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.141626] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.171326] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.199227] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.217868] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.236508] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.255472] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.273873] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.293120] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.311285] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.331447] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.352891] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.370203] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.387991] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.406869] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.423695] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.442498] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.462203] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.480815] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.500054] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.519158] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.536619] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.555897] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.574639] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.592737] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.613692] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.631018] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.649392] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.670302] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.687744] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.705715] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.723991] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.741616] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.759519] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.778404] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.795185] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.814587] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.832950] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.852035] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.871743] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.893119] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.912342] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.932720] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.953019] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.971966] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1287.990436] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.010974] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.029456] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.048783] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.067876] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.086150] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.107018] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.125506] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.143450] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.162254] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.179598] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.197926] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.215438] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.234980] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.257357] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.277886] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.297589] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.317267] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.336802] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.355921] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.374653] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.392864] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.410896] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.428523] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.446135] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.464073] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.482344] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.499986] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.518410] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.535633] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.553690] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.571106] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.594792] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.614959] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.635412] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.657859] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.678279] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.697412] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.715654] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.734026] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.755141] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.774296] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.792486] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.815591] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.834092] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.853028] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.870995] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.889733] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.907766] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.925036] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.942912] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.960881] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1288.982285] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.001971] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.020995] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.038867] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.055352] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.073142] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.090763] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.113278] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.134709] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.158710] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.179909] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.199518] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.218694] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.238708] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.257732] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.279078] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.302344] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.321233] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.341022] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.358880] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.376285] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.393918] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.411760] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.434849] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.458260] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.481169] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.505088] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.525075] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.544774] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.563853] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.582131] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.601425] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.622080] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.639411] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.657324] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.675369] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.695283] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.713433] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.732224] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.751389] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.770600] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.788151] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.804864] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.822888] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.839988] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.857503] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.875174] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.893113] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.910454] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.931584] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.954544] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.974726] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1289.994639] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.016452] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.039677] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.063720] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.081152] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.100822] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.119066] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.142213] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.165455] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.189158] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.210439] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.229452] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.249400] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.271327] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.297042] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.322345] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.342744] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.361901] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.382991] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.406257] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.426693] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.446462] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.463127] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.479204] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.496954] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.516157] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.538572] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.562632] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.580286] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.598568] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.617641] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.636642] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.657905] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.682331] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.706335] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.725261] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.743485] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.761652] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.778145] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.796492] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.815909] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.834059] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.852235] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.869935] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.887327] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.906893] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.926058] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.949265] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.969066] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1290.987085] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.005578] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.026499] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.048994] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.067980] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.088075] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.111147] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.130269] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.150346] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.171227] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.190189] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.210663] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.230753] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.248766] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.268693] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.287649] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.305608] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.323548] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.342225] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.361679] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.382710] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.404079] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.423693] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.441869] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.461676] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.481305] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.502442] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.525815] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.549804] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.570231] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.589302] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.608799] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.632031] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.650935] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.674098] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.698835] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.722820] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.746602] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.766208] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.784399] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.806267] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.825110] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.845689] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.862966] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.881951] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.899324] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.916825] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.939660] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.963091] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1291.983882] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.010188] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.033774] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.056753] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.080042] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.103929] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.126658] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.149764] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.172400] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.195118] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.218336] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.241221] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.263759] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.286881] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.305721] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.326582] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.345635] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.363328] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.381069] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.399461] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.419987] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.440653] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.462421] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.483474] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.502383] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.521867] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.539947] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.557053] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.578166] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.598176] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.619188] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.639212] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.658829] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.677348] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.696627] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.715806] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.734811] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.754374] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.773187] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.792208] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.810112] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.828353] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.846784] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.866444] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.885515] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.902347] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.920012] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.938654] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.957021] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.975728] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1292.994020] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.013609] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.031987] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.049685] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.068183] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.086138] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.106662] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.124517] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.143659] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.166913] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.185219] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.208522] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.229663] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.250204] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.273168] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.296806] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.317439] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.338498] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.358177] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.377859] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.396364] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.417573] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.440403] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.459960] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.479572] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.498683] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.517912] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.535660] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.554342] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.573085] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.593713] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.613918] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.634563] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.654216] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.675205] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.693596] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.714523] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.734542] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.758987] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.781762] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.799511] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.819488] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.840123] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.859088] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.878080] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.897253] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.918849] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.937772] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.956108] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.975246] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1293.994547] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.015704] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.034869] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.057096] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.080554] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.098244] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.117277] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.138408] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.157571] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.176950] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.196754] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.214308] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.234482] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.251916] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.270421] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.289242] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.308083] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.327608] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.347573] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.365777] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.384463] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.404222] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.422777] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.440859] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.461440] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.481325] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.500888] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.519660] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.538176] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.561419] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.581048] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.602392] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.620595] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.639643] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.657808] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.677220] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.696153] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.714834] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.738566] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.757100] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.775964] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.795061] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.814089] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.837412] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.861502] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.878981] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.896695] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.914652] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.934642] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.953451] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1294.976564] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.000747] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.024136] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.042125] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.058760] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.080538] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.103622] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.126657] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.148070] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.166058] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.182958] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.199291] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.216197] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.234757] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.254458] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.274927] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.294110] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.311985] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.330590] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.350950] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.369188] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.387647] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.406032] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.422992] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.442509] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.460928] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.481305] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.498461] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.516665] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.536338] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.556611] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.575492] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.598278] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.617256] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.636319] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.655299] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.674358] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.692670] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.711508] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.729671] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.747340] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.766395] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.785489] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.803984] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.822213] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.840835] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.865202] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.889223] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.912958] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.936639] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.960313] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1295.981543] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.004246] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.023752] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.042803] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.067667] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.092749] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.118166] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.135971] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.153798] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.173885] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.196580] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.213901] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.232294] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.252589] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.269239] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.286902] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.307579] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.325266] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.345260] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.365705] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.384768] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.403453] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.425859] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.443799] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.461726] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.479133] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.496593] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.513971] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.533523] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.551130] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.568618] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.586882] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.604758] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.622235] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.639711] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.658855] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.675689] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.693562] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.710979] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.728222] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.746883] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.763371] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.781044] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.800016] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.817076] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.835981] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.854395] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.875260] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.895133] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.916377] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.934750] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.953887] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.972889] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1296.991542] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.010071] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.028681] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.046782] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.063528] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.081425] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.097905] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.114161] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.131738] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.151431] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.169631] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.186866] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.203888] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.221671] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.240146] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.257568] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.275996] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.292449] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.308649] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.326133] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.342486] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.359165] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.375624] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.392491] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.409164] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.425735] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.442646] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.459953] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.477332] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.495292] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.512662] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.531675] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.550836] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.567582] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.584879] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.602945] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.621152] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.640754] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.662865] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.682402] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.703766] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.722736] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.739827] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.756992] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.774276] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.791631] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.809025] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.826463] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.843568] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.860356] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.876915] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.895301] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.913558] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.932133] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.948883] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.966725] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1297.983800] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.002936] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.021090] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.039982] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.059297] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.079853] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.098104] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.115543] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.137703] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.159375] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.179277] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.199267] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.218973] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.241775] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.265253] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.282274] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.299544] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.316210] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.334924] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.352690] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.374577] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.392837] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.410788] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.433146] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.456064] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.476510] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.498873] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.517089] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.534901] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.552509] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.569814] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.587429] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.605861] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.624695] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.643415] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.663625] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.680967] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.697858] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.715263] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.733406] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.756639] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.775913] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.793849] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.810464] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.834125] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.852734] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.870364] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.888967] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.906278] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.924105] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.941746] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.961603] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1298.978772] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.000515] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.022622] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.042931] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.063092] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.083198] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.102376] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.122534] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.144164] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.166316] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.188134] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.210144] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.228789] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.247574] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.265023] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.291208] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.317410] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.339029] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.357112] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.379050] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.403753] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.428867] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.460490] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.482061] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.510550] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.535119] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.553383] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.572212] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.590438] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.608600] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.628534] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.652057] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.675938] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.693645] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.713816] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.736208] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.758634] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.782653] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.807419] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.827624] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.844533] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.861748] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.881134] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.906185] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.931204] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.936238] Lustre: lustre-OST0000-osc-ffff96ae4e5e7800: disconnect after 20s idle [ 1299.954109] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.975476] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1299.996833] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.020626] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.045088] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.064907] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.088799] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.112597] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.134737] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.153668] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.176765] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.202032] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.224973] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.248906] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.271127] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.288499] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.305498] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.322163] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.340305] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.358414] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.375249] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.393224] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.414208] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.436153] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.458223] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.481097] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.499824] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.518193] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.540572] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.560301] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.583955] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.608279] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.632616] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.653907] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.672110] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.689457] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.707593] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.725208] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.742733] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.760948] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.779945] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.797892] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.817761] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.836783] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.855752] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.873966] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.892399] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.910807] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.928253] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.945649] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.962955] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.979844] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1300.997087] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.014281] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.031746] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.049154] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.069517] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.088985] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.112472] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.134323] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.154620] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.173967] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.195541] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.213916] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.235387] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.258297] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.281885] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.305860] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.324838] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.342397] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.362207] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.383710] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.404046] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.423778] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.440465] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.457425] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.474288] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.495374] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.516492] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.535067] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.554474] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.571450] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.591478] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.608793] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.627743] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.647988] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.666989] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1301.689968] rw_seq_cst_vs_d (32503): drop_caches: 3 [ 1303.763417] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 18:29:08 (1778279348) [ 1303.863147] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1303.908457] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1303.969603] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.008555] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.030597] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.058604] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.084844] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.174128] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.202324] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.248748] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.265047] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.288164] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.312058] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.395855] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.455623] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.505544] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.547873] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.568495] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.629183] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.653809] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.693626] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.718192] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.746514] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.766780] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.788477] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.806339] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.837584] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.863579] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.886056] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1304.976684] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.026128] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.056919] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.085566] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.138608] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.166933] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.188159] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.214133] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.337762] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.354791] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.376334] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.398180] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.420943] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.442631] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.458104] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.473717] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.490084] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.507229] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.528708] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.543304] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.561084] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.579277] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.600687] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.615664] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.631382] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.647149] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.705372] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.754422] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.780482] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.804042] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.824563] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.849910] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.875190] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.902603] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.917775] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.937855] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.958438] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.973098] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1305.992388] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.013682] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.032293] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.048231] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.063166] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.125658] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.247502] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.263827] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.278647] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.294118] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.310113] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.326323] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.392283] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.408615] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.499962] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.515506] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.532126] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.546950] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.565668] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.586951] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.602917] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.616308] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.630167] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.646130] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.687707] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.702986] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.720592] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.744215] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.770567] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.808320] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.827830] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.873250] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.889391] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.906689] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.921415] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.947457] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.967287] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1306.988869] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.012511] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.032586] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.051554] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.067544] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.087396] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.107838] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.128591] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.148200] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.216955] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.232606] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.248187] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.289718] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.326811] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.343632] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.388249] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.402608] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.420416] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.437381] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.454571] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.495423] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.520030] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.540728] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.647944] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.667092] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.683866] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.700541] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.721451] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.854690] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.876630] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.893941] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.910050] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.927935] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1307.945325] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.035683] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.055987] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.081847] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.189839] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.205915] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.221576] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.237217] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.252856] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.268120] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.286303] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.306932] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.321962] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.337972] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.353477] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.370909] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.391709] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.476355] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.493060] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.507916] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.527551] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.543363] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.558816] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.710243] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.726287] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.742136] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.756897] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.775045] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.796275] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.811501] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.827848] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.845289] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.861939] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.877240] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.956788] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.974256] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1308.998253] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.019042] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.033766] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.049849] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.065073] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.080572] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.096045] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.111320] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.126469] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.142536] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.158318] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.181096] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.220654] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.239370] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.261919] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.280528] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.300591] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.349263] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.370468] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.650605] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.667791] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.684523] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.700361] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.722114] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.782043] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.802417] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.825322] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.890275] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.906688] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.929218] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.977557] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1309.993747] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.009288] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.026067] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.041852] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.061770] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.082800] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.098433] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.195024] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.214652] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.231120] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.247512] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.265319] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.290799] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.335520] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.375559] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.390770] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.406859] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.424435] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.442542] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.459838] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.479589] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.505101] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.554606] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.577110] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.595333] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.612093] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.643656] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.664742] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.681755] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.699828] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.717614] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.734112] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.750122] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.767090] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.861847] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1310.879343] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.031385] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.049517] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.090290] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.113833] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.142823] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.209849] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.224420] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.242244] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.260985] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.281521] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.300210] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.321630] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.342403] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.361138] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.421509] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.464539] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.493270] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.513664] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.530835] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.547647] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.563986] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.581976] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.599556] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.617472] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.634911] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.662640] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.715319] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.761215] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.850379] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.874699] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.906678] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.930882] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1311.956972] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.045779] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.069416] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.091181] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.171400] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.193113] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.218176] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.241245] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.327709] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.344870] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.363774] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.381672] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.404315] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.526619] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.586585] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.601471] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.616972] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.684923] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.748166] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.930114] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.951683] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.969104] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1312.989700] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.006432] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.024897] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.041551] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.059547] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.077026] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.095066] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.111736] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.128252] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.144838] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.165481] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.185032] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.205062] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.221020] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.289415] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.310820] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.328409] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.352256] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.517338] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.537266] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.554373] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.573586] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.955216] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1313.969764] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.048589] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.064361] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.103975] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.264887] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.285656] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.310246] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.328852] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.344841] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.361066] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.385344] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.406728] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.427480] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.453147] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.477299] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.734969] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.759601] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.815675] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.836472] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.919478] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.936290] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.958579] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1314.993200] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.011989] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.028796] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.044877] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.060963] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.076698] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.092595] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.110863] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.127776] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.145564] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.169065] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.195411] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.215112] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.233081] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.248883] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.264352] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.279682] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.295959] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.314672] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.379656] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.398522] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.421726] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.570942] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.586337] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.606890] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.757982] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.791188] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.808330] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.840209] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.855413] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.873287] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.954597] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.969691] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1315.986376] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.006369] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.020878] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.037325] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.060328] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.080491] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.098442] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.112867] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.128339] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.143744] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.159645] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.175359] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.190517] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.205956] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.221641] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.263797] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.284355] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.299873] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.317650] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.333204] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.348494] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.403856] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.418402] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.436829] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.456337] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.474781] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.492165] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.513954] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.536915] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.614661] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.629997] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.645608] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.664662] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.691115] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.710121] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.725896] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.742140] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.758497] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.773942] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.878746] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.896556] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.913269] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1316.935554] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.129093] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.145227] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.182059] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.205379] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.241819] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.257222] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.272869] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.290070] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.316734] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.345865] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.364885] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.381196] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.398332] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.414728] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.433302] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.453541] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.468909] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.486703] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.503371] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.536885] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.552531] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.568837] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.584744] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.790455] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.811647] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.827707] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.843373] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1317.861899] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.037142] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.056326] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.071751] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.090696] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.106242] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.125347] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.181890] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.202478] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.218869] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.236110] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.252172] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.270890] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.302057] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.325192] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.348251] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.368376] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.445685] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.575266] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.753748] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.770877] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.853420] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.977339] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1318.993641] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.007832] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.128960] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.143863] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.166304] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.256692] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.278352] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.330336] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.382793] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.400386] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.415499] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.431347] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.638062] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.658469] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.675145] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.690607] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.739403] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.802801] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.855264] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.877702] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.892481] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.907661] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.969873] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1319.988910] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.010071] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.119646] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.144372] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.168915] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.192086] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.212791] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.228840] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.245123] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.261824] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.603233] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.622323] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.645622] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.664221] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.686694] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.705243] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.725463] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.742704] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.927342] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.977946] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1320.997556] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.021707] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.042836] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.058521] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.075556] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.093481] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.115358] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.149398] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.167362] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.189284] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.210024] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.230240] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.249779] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.275776] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.294255] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.542665] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.560957] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.585925] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.692533] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.710552] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.725708] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.743501] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.760484] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.780246] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.802087] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.818804] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.839907] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.856166] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.873086] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.888979] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.950509] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.977075] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1321.992896] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.009603] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.075750] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.098319] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.120670] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.141897] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.157754] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.172442] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.215541] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.233769] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.248667] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.262987] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.281120] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.296617] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.310878] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.325461] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.341701] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.361814] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.383979] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.405104] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.424585] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.440403] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.459375] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.477920] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.556896] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.744720] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.764668] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.847847] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.867228] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.889182] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.905308] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.920166] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.941742] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.965154] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1322.987077] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.070529] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.089359] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.107194] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.124135] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.139740] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.155977] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.172827] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.188750] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.206399] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.229184] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.356558] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.627790] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.650758] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1323.667542] rw_seq_cst_vs_d (33082): drop_caches: 3 [ 1326.162119] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 18:29:31 (1778279371) [ 1328.365784] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 18:29:33 (1778279373) [ 1330.516330] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 18:29:35 (1778279375) [ 1338.245314] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 18:29:43 (1778279383) [ 1338.401935] bash (35565): drop_caches: 3 [ 1341.494923] bash (35565): drop_caches: 3 [ 1344.669157] bash (35565): drop_caches: 3 [ 1348.198165] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 18:29:53 (1778279393) [ 1352.837792] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 18:29:57 (1778279397) [ 1364.261773] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 18:30:09 (1778279409) [ 1367.068968] Lustre: DEBUG MARKER: loop 5 [ 1368.841118] Lustre: DEBUG MARKER: loop 10 [ 1370.563189] Lustre: DEBUG MARKER: loop 15 [ 1372.305662] Lustre: DEBUG MARKER: loop 20 [ 1374.911438] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 18:30:19 (1778279419) [ 1377.128164] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 18:30:22 (1778279422) [ 1379.229507] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 18:30:24 (1778279424) [ 1381.855205] Lustre: lustre-OST0000-osc-ffff96ae4e5e7800: disconnect after 22s idle [ 1397.215310] Lustre: lustre-OST0001-osc-ffff96ae4ce83000: disconnect after 22s idle [ 1402.335224] Lustre: lustre-OST0000-osc-ffff96ae4ce83000: disconnect after 22s idle [ 1402.337241] Lustre: Skipped 1 previous similar message [ 1442.885948] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 18:31:27 (1778279487) [ 1445.024856] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 18:31:30 (1778279490) [ 1447.226928] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 18:31:32 (1778279492) [ 1449.392568] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 18:31:34 (1778279494) [ 1451.696826] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 18:31:36 (1778279496) [ 1454.727559] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 18:31:39 (1778279499) [ 1458.823336] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 18:31:43 (1778279503) [ 1461.903234] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1462.402248] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 18:31:47 (1778279507) [ 1466.675452] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 18:31:51 (1778279511) [ 1466.795920] Lustre: *** cfs_fail_loc=314, val=0*** [ 1467.792753] Lustre: *** cfs_fail_loc=314, val=0*** [ 1467.793900] Lustre: Skipped 2 previous similar messages [ 1469.708803] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 18:31:54 (1778279514) [ 1476.100243] Lustre: *** cfs_fail_loc=314, val=0*** [ 1476.101439] Lustre: Skipped 1 previous similar message [ 1476.123133] LustreError: lustre-OST0000-osc-ffff96ae4e5e7800: operation ldlm_enqueue to node 192.168.201.103@tcp failed: rc = -107 [ 1476.126625] Lustre: lustre-OST0000-osc-ffff96ae4e5e7800: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1476.132144] LustreError: lustre-OST0000-osc-ffff96ae4e5e7800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1476.136498] LustreError: 46571:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff96ae4e5e7800: namespace resource [0x280000401:0x37:0x0].0x0 (ffff96ae4eb57000) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1476.142028] Lustre: lustre-OST0000-osc-ffff96ae4e5e7800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 1478.311607] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 18:32:03 (1778279523) [ 1478.383175] LustreError: 47160:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1481.399141] LustreError: 47160:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1483.414758] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 18:32:08 (1778279528) [ 1485.993725] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 18:32:11 (1778279531) [ 1488.986101] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1489.517747] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 18:32:14 (1778279534) [ 1490.088355] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1490.619216] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 18:32:15 (1778279535) [ 1494.497104] Lustre: lustre-MDT0000-mdc-ffff96ae4ce83000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1499.617460] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 1499.622109] Lustre: Evicted from MGS (at 192.168.201.103@tcp) after server handle changed from 0x59f02adec4f4c61b to 0x59f02adec5021a9b [ 1499.624994] Lustre: MGC192.168.201.103@tcp: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 1512.465872] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 18:32:37 (1778279557) [ 1536.904181] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 18:33:01 (1778279581) [ 1542.037925] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 18:33:07 (1778279587) [ 1591.777055] Lustre: lustre-OST0000-osc-ffff96ae4ce83000: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1591.781237] Lustre: Skipped 1 previous similar message [ 1591.783787] LustreError: lustre-OST0000-osc-ffff96ae4ce83000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1591.786977] LustreError: lustre-OST0000-osc-ffff96ae4e5e7800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1591.787476] Lustre: lustre-OST0000-osc-ffff96ae4ce83000: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 1591.792464] Lustre: Skipped 3 previous similar messages [ 1606.070341] Lustre: lustre-OST0001-osc-ffff96ae4ce83000: Connection to lustre-OST0001 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1606.076191] Lustre: Skipped 1 previous similar message [ 1606.079886] LustreError: lustre-OST0001-osc-ffff96ae4ce83000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1606.085967] Lustre: lustre-OST0001-osc-ffff96ae4ce83000: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 1612.255208] Lustre: lustre-OST0000-osc-ffff96ae4ce83000: disconnect after 20s idle [ 1616.828871] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff96ae4ce83000.ost_server_uuid 50 [ 1617.300773] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff96ae4ce83000.ost_server_uuid in IDLE state after 0 sec [ 1618.429436] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff96ae4ce83000.ost_server_uuid 50 [ 1618.912809] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff96ae4ce83000.ost_server_uuid in FULL state after 0 sec [ 1620.452941] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff96ae4ce83000.ost_server_uuid 50 [ 1620.940630] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff96ae4ce83000.ost_server_uuid in IDLE state after 0 sec [ 1622.175127] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff96ae4ce83000.ost_server_uuid 50 [ 1622.636864] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff96ae4ce83000.ost_server_uuid in FULL state after 0 sec [ 1625.592146] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff96ae4ce83000.ost_server_uuid 50 [ 1626.094338] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff96ae4ce83000.ost_server_uuid in IDLE state after 0 sec [ 1627.284552] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff96ae4ce83000.ost_server_uuid 50 [ 1627.615273] Lustre: lustre-OST0001-osc-ffff96ae4ce83000: disconnect after 22s idle [ 1627.617946] Lustre: Skipped 1 previous similar message [ 1627.773643] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff96ae4ce83000.ost_server_uuid in FULL state after 0 sec [ 1628.311692] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 18:34:33 (1778279673) [ 1629.217868] Lustre: DEBUG MARKER: Race attempt 0 [ 1630.722136] Lustre: DEBUG MARKER: Wait for 58493 58628 for 60 sec... [ 1693.174510] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 18:35:38 (1778279738) [ 1698.436391] Lustre: DEBUG MARKER: start test - cycle (0) [ 1717.287687] Lustre: DEBUG MARKER: start test - cycle (1) [ 1731.955977] Lustre: DEBUG MARKER: start test - cycle (2) [ 1746.679263] Lustre: DEBUG MARKER: start test - cycle (3) [ 1762.607517] Lustre: DEBUG MARKER: start test - cycle (4) [ 1778.670298] Lustre: DEBUG MARKER: start test - cycle (5) [ 1793.299846] Lustre: DEBUG MARKER: start test - cycle (6) [ 1809.345887] Lustre: DEBUG MARKER: start test - cycle (7) [ 1828.421515] Lustre: DEBUG MARKER: start test - cycle (8) [ 1844.268133] Lustre: DEBUG MARKER: start test - cycle (9) [ 1860.269360] Lustre: DEBUG MARKER: start test - cycle (10) [ 1882.470815] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 18:38:47 (1778279927) [ 1888.735150] Lustre: lustre-OST0000-osc-ffff96ae4e5e7800: disconnect after 22s idle [ 1896.989559] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 18:39:02 (1778279942) [ 1899.403782] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 18:39:04 (1778279944) [ 1902.689722] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 18:39:07 (1778279947) [ 1906.168987] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 18:39:11 (1778279951) [ 1906.235465] Lustre: *** cfs_fail_loc=411, val=0*** [ 1908.265594] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1908.796857] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 18:39:13 (1778279953) [ 1917.039844] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 18:39:22 (1778279962) [ 1925.172614] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 18:39:30 (1778279970) [ 1933.249535] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 18:39:38 (1778279978) [ 1934.815351] Lustre: lustre-OST0000-osc-ffff96ae4ce83000: disconnect after 20s idle [ 1940.859476] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 18:39:45 (1778279985) [ 1946.330272] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 18:39:51 (1778279991) [ 1951.743026] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 18:39:56 (1778279996) [ 1956.958022] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 18:40:02 (1778280002) [ 1962.175855] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 18:40:07 (1778280007) [ 1967.499735] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 18:40:12 (1778280012) [ 1973.037866] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 18:40:18 (1778280018) [ 1978.484031] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 18:40:23 (1778280023) [ 1983.968994] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 18:40:29 (1778280029) [ 2001.375319] Lustre: lustre-OST0001-osc-ffff96ae4ce83000: disconnect after 20s idle [ 2001.378061] Lustre: Skipped 1 previous similar message [ 2605.535228] Lustre: lustre-OST0000-osc-ffff96ae4e5e7800: disconnect after 22s idle [ 2605.537601] Lustre: Skipped 1 previous similar message [ 2704.926369] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 18:52:30 (1778280750) [ 2709.982910] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 18:52:35 (1778280755) [ 2715.143383] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 18:52:40 (1778280760) [ 2720.182187] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 18:52:45 (1778280765) [ 2725.338938] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 18:52:50 (1778280770) [ 2730.466180] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 18:52:55 (1778280775) [ 2735.619329] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 18:53:00 (1778280780) [ 2740.821326] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 18:53:05 (1778280785) [ 2746.414251] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 18:53:11 (1778280791) [ 2771.665946] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 18:53:36 (1778280816) [ 2776.708860] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 18:53:41 (1778280821) [ 2781.794178] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 18:53:46 (1778280826) [ 2786.861236] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 18:53:51 (1778280831) [ 2792.019795] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 18:53:57 (1778280837) [ 2797.123155] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 18:54:02 (1778280842) [ 2802.292639] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 18:54:07 (1778280847) [ 2807.615687] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 18:54:12 (1778280852) [ 2812.824391] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 18:54:17 (1778280857) [ 2850.770375] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 18:54:55 (1778280895) [ 2882.015177] Lustre: lustre-OST0001-osc-ffff96ae4ce83000: disconnect after 21s idle [ 2882.017435] Lustre: Skipped 5 previous similar messages [ 3309.972124] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 19:02:35 (1778281355) [ 3315.377530] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 19:02:40 (1778281360) [ 3320.702730] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 19:02:45 (1778281365) [ 3325.831675] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 19:02:50 (1778281370) [ 3330.797772] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 19:02:55 (1778281375) [ 3335.922913] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 19:03:00 (1778281380) [ 3341.190239] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 19:03:06 (1778281386) [ 3346.044177] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 19:03:11 (1778281391) [ 3351.083913] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 19:03:16 (1778281396) [ 3356.608635] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 19:03:21 (1778281401) [ 3396.685536] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 19:04:01 (1778281441) [ 3401.985369] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 19:04:07 (1778281447) [ 3407.513180] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 19:04:12 (1778281452) [ 3413.006844] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 19:04:18 (1778281458) [ 3418.441550] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 19:04:23 (1778281463) [ 3423.750459] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 19:04:28 (1778281468) [ 3429.016641] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 19:04:34 (1778281474) [ 3433.743338] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 19:04:38 (1778281478) [ 3438.951585] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 19:04:44 (1778281484) [ 3890.011932] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 19:12:15 (1778281935) [ 3895.189543] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 19:12:20 (1778281940) [ 3900.240521] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 19:12:25 (1778281945) [ 3905.313257] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 19:12:30 (1778281950) [ 3910.467413] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 19:12:35 (1778281955) [ 3915.539146] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 19:12:40 (1778281960) [ 3920.530114] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 19:12:45 (1778281965) [ 3925.531878] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 19:12:50 (1778281970) [ 3930.457481] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 19:12:55 (1778281975) [ 3935.430593] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 19:13:00 (1778281980) [ 3940.181020] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 19:13:05 (1778281985) [ 3945.918915] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 19:13:11 (1778281991) [ 3950.848919] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 19:13:15 (1778281995) [ 3955.854602] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 19:13:20 (1778282000) [ 3960.682102] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 19:13:25 (1778282005) [ 3965.459144] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 19:13:30 (1778282010) [ 3971.404081] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 19:13:36 (1778282016) [ 3971.481375] LustreError: 22699:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3973.567131] LustreError: 22699:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 3978.601721] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 19:13:43 (1778282023) [ 3982.668732] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 19:13:47 (1778282027) [ 3982.751529] LustreError: 240599:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3986.807108] LustreError: 240599:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3986.812946] LustreError: 240599:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3990.871113] LustreError: 240599:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3990.882816] LustreError: 240606:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3994.943130] LustreError: 240606:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3997.234378] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 19:14:02 (1778282042) [ 4003.615693] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 19:14:08 (1778282048) [ 4006.602117] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 19:14:11 (1778282051) [ 4010.520610] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 19:14:15 (1778282055) [ 4018.655155] Lustre: lustre-OST0000-osc-ffff96ae4ce83000: disconnect after 22s idle [ 4018.657031] Lustre: Skipped 4 previous similar messages [ 4034.451374] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 19:14:39 (1778282079) [ 4041.727335] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 19:14:46 (1778282086) [ 4049.007656] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 19:14:54 (1778282094) [ 4061.697321] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 19:15:06 (1778282106) [ 4071.128294] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 19:15:16 (1778282116) [ 4083.830833] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 19:15:28 (1778282128) [ 4096.575470] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 19:15:41 (1778282141) [ 4110.668854] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 19:15:55 (1778282155) [ 4117.809536] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 19:16:02 (1778282162) [ 4148.708694] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 19:16:33 (1778282193) [ 4150.852026] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4153.154340] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 19:16:38 (1778282198) [ 4155.831110] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 19:16:40 (1778282200) [ 4158.059500] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 19:16:43 (1778282203) [ 4160.134084] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 19:16:45 (1778282205) [ 4170.329544] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 19:16:55 (1778282215) [ 4183.918248] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 19:17:08 (1778282228) [ 4186.098044] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 19:17:11 (1778282231) [ 4188.210106] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 19:17:13 (1778282233) [ 4191.278969] LustreError: lustre-MDT0000-mdc-ffff96ae4e5e7800: operation ldlm_enqueue to node 192.168.201.103@tcp failed: rc = -35 [ 4191.281374] LustreError: Skipped 1 previous similar message [ 4194.287379] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 19:17:19 (1778282239) [ 4194.425447] LustreError: 2414:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4196.511103] LustreError: 2414:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 4201.375805] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 19:17:26 (1778282246) [ 4218.335161] Lustre: lustre-OST0000-osc-ffff96ae4e5e7800: disconnect after 24s idle [ 4250.211159] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 19:18:15 (1778282295) [ 4252.986233] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 19:18:18 (1778282298) [ 4256.803268] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 19:18:21 (1778282301) [ 4261.469749] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 19:18:26 (1778282306) [ 4265.906243] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 19:18:31 (1778282311) [ 4272.510859] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 19:18:37 (1778282317) [ 4279.238935] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 19:18:44 (1778282324) [ 4282.371615] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 19:18:47 (1778282327) [ 4285.849488] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 19:18:50 (1778282330) [ 4292.992135] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 19:18:58 (1778282338) [ 4333.109260] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 19:19:38 (1778282378) [ 4356.575183] Lustre: lustre-OST0001-osc-ffff96ae4ce83000: disconnect after 22s idle [ 4356.577318] Lustre: Skipped 4 previous similar messages [ 4445.927372] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 19:21:30 (1778282490) [ 4576.679991] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 19:23:41 (1778282621) [ 4627.940442] Lustre: lustre-OST0001-osc-ffff96ae4ce83000: disconnect after 20s idle [ 4627.946086] Lustre: Skipped 5 previous similar messages [ 4887.963852] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 19:28:52 (1778282932) [ 4921.281926] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 19:29:25 (1778282965) [ 4959.002475] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 19:30:03 (1778283003) [ 4989.127846] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 19:30:33 (1778283033) [ 5059.282598] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 19:31:43 (1778283103) [ 5121.080911] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 19:32:45 (1778283165) [ 5145.055119] Lustre: lustre-OST0001-osc-ffff96ae4ce83000: disconnect after 21s idle [ 5145.058354] Lustre: Skipped 10 previous similar messages [ 5217.814317] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 19:34:22 (1778283262) [ 5219.957379] LustreError: 286998:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff96ae4ce83000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5220.000330] Lustre: Unmounted lustre-client [ 5222.168184] LustreError: 287011:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff96ae4e5e7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5222.173771] LustreError: 287011:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5222.214542] Lustre: Unmounted lustre-client [ 5303.116154] Lustre: Mounted lustre-client [ 5304.941834] Lustre: Mounted lustre-client [ 5306.421640] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5382.584489] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 19:37:07 (1778283427) [ 5394.898485] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 19:37:19 (1778283439) [ 5401.628921] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 19:37:26 (1778283446) [ 5459.224977] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 19:38:23 (1778283503) [ 5521.692435] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 19:39:26 (1778283566) [ 5532.056687] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 19:39:36 (1778283576) [ 5628.769040] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 19:41:13 (1778283673) [ 5658.166438] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 19:41:42 (1778283702) [ 5709.365128] Lustre: DEBUG MARKER: == sanityn test 77s: Check TBF LRU shrinker ============== 19:42:33 (1778283753) [ 5745.631644] Lustre: lustre-OST0000-osc-ffff96ae749c6800: disconnect after 21s idle [ 5745.638517] Lustre: Skipped 7 previous similar messages [ 5756.850869] Lustre: DEBUG MARKER: == sanityn test 77t: check TBF minrate parsing and output ========================================================== 19:43:21 (1778283801) [ 5774.806987] Lustre: DEBUG MARKER: == sanityn test 77u: check TBF minrate on NID and generic rules ========================================================== 19:43:39 (1778283819) [ 5790.848549] Lustre: DEBUG MARKER: == sanityn test 77v: verify minimum RPC rate under congestion ========================================================== 19:43:55 (1778283835) [ 5792.121598] Lustre: DEBUG MARKER: SKIP: sanityn test_77v need OSTs and MDTs on separate nodes [ 5793.560558] Lustre: DEBUG MARKER: == sanityn test 77w: check TBF cost_model inference from rate format ========================================================== 19:43:57 (1778283837) [ 5816.112411] Lustre: DEBUG MARKER: == sanityn test 77x: check TBF bandwidth suffix rate parsing ========================================================== 19:44:20 (1778283860) [ 5839.441872] Lustre: DEBUG MARKER: == sanityn test 77y: verify page-weighted cost_model under congestion ========================================================== 19:44:43 (1778283883) [ 5840.788225] Lustre: DEBUG MARKER: SKIP: sanityn test_77y need OSTs and MDTs on separate nodes [ 5842.249968] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 19:44:46 (1778283886) [ 5849.505782] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 19:44:53 (1778283893) [ 5865.917793] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 19:45:10 (1778283910) [ 5873.237287] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 19:45:17 (1778283917) [ 5874.701831] LustreError: 310878:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x21:0x0]: rc = -5 [ 5874.709915] LustreError: 310878:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5875.218418] LustreError: 311051:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x5d5:0x0]: rc = -5 [ 5875.227651] LustreError: 311051:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5876.465521] LustreError: 311082:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x5e1:0x0]: rc = -5 [ 5876.473438] LustreError: 311082:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 5 previous similar messages [ 5876.475329] LustreError: 311082:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5876.480702] LustreError: 311082:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 5 previous similar messages [ 5878.610358] LustreError: 311143:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x44:0x0]: rc = -5 [ 5878.616840] LustreError: 311143:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 11 previous similar messages [ 5878.625587] LustreError: 311143:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5878.631781] LustreError: 311143:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 11 previous similar messages [ 5882.880432] LustreError: 311263:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x6e:0x0]: rc = -5 [ 5882.890114] LustreError: 311263:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 25 previous similar messages [ 5882.896517] LustreError: 311263:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5882.902512] LustreError: 311263:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 25 previous similar messages [ 5891.118332] LustreError: 311483:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x656:0x0]: rc = -5 [ 5891.126564] LustreError: 311483:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 62 previous similar messages [ 5891.131926] LustreError: 311483:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5891.141710] LustreError: 311483:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 62 previous similar messages [ 5902.912983] LustreError: 311786:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff96ae4e019000: inode [0x2000013a1:0x690:0x0] mdc close failed: rc = -2 [ 5907.121726] LustreError: 311920:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x138:0x0]: rc = -5 [ 5907.131756] LustreError: 311920:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 128 previous similar messages [ 5907.138213] LustreError: 311920:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5907.141302] LustreError: 311920:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 128 previous similar messages [ 5938.431286] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 19:46:22 (1778283982) [ 5943.341918] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 19:46:27 (1778283987) [ 6081.180618] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 19:48:45 (1778284125) [ 6082.162995] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6083.146411] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 19:48:47 (1778284127) [ 6210.950438] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 19:50:55 (1778284255) [ 6214.635366] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 19:50:59 (1778284259) [ 6338.526923] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 19:53:03 (1778284383) [ 6347.308462] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 19:53:12 (1778284392) [ 6352.191468] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 19:53:17 (1778284397) [ 6535.506216] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 19:56:20 (1778284580) [ 6554.592402] Lustre: lustre-OST0001-osc-ffff96ae749c6800: disconnect after 23s idle [ 6554.597023] Lustre: Skipped 5 previous similar messages [ 6718.808488] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 19:59:23 (1778284763) [ 6721.806104] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 19:59:26 (1778284766) [ 6731.662147] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 19:59:36 (1778284776) [ 6731.754916] Lustre: DEBUG MARKER: write [ 6731.779332] LustreError: 289411:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6733.784801] Lustre: DEBUG MARKER: kill 354193 [ 6733.787088] LustreError: 354193:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6736.783151] LustreError: 289411:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 6739.823160] LustreError: 354193:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 6742.739809] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 19:59:47 (1778284787) [ 6745.086574] LustreError: 354808:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 6755.087135] LustreError: 354808:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [ 6758.518602] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 20:00:03 (1778284803) [ 6758.692973] LustreError: 355395:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6760.775352] LustreError: 355395:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [ 6767.416249] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 20:00:12 (1778284812) [ 6768.124367] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6768.914834] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 20:00:13 (1778284813) [ 6772.370589] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 20:00:17 (1778284817) [ 6775.162101] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 20:00:20 (1778284820) [ 6778.341786] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 20:00:23 (1778284823) [ 6781.794860] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 20:00:26 (1778284826) [ 6784.898068] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 20:00:29 (1778284829) [ 6788.156206] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 20:00:33 (1778284833) [ 6792.417688] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 20:00:37 (1778284837) [ 6795.982710] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 20:00:40 (1778284840) [ 6796.814588] Lustre: *** cfs_fail_loc=415, val=0*** [ 6804.534723] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 20:00:49 (1778284849) [ 6824.808195] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 20:01:09 (1778284869) [ 6824.917830] LustreError: 289413:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6824.920928] LustreError: 289413:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 6829.919125] LustreError: 289413:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 6829.921846] LustreError: 289413:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 1 previous similar message [ 6840.127207] LustreError: 289411:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 6840.130470] LustreError: 289411:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 6 previous similar messages [ 6843.031660] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 20:01:27 (1778284887) [ 6846.268411] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 20:01:31 (1778284891) [ 6849.187158] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 20:01:34 (1778284894) [ 6851.918088] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 20:01:36 (1778284896) [ 6856.550191] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 20:01:41 (1778284901) [ 6865.444424] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 20:01:50 (1778284910) [ 6865.669917] LustreError: 366140:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6865.672146] LustreError: 366140:0:(osc_request.c:2989:osc_build_rpc()) Skipped 6 previous similar messages [ 6869.727339] LustreError: 366140:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6869.730435] LustreError: 366140:0:(osc_request.c:2989:osc_build_rpc()) Skipped 1 previous similar message [ 6872.224976] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 20:01:57 (1778284917) [ 6873.715308] LustreError: 366850:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff96ae749c6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6873.719819] LustreError: 366850:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 6873.758276] Lustre: Unmounted lustre-client [ 6874.416652] LustreError: 366870:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff96ae4e019000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6874.420625] LustreError: 366870:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 6874.459142] Lustre: Unmounted lustre-client [ 6875.025290] Lustre: DEBUG MARKER: Iteration 0 [ 6875.139141] LustreError: 367033:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6875.139180] LustreError: 367034:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6875.144678] LustreError: 367033:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6875.205047] Lustre: Mounted lustre-client [ 6875.717223] LustreError: 367139:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff96ae523de800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6875.721082] LustreError: 367139:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 6875.807959] Lustre: Unmounted lustre-client [ 6876.988907] Key type lgssc unregistered [ 6877.120412] LNet: 367384:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6877.124919] LNetError: 367384:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6877.138994] LNet: Removed LNI 192.168.201.3@tcp [ 6877.488109] Key type .llcrypt unregistered [ 6877.489441] Key type ._llcrypt unregistered [ 6877.757254] Key type ._llcrypt registered [ 6877.759132] Key type .llcrypt registered [ 6878.043801] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6878.050097] alg: No test for adler32 (adler32-zlib) [ 6879.047510] Lustre: Lustre: Build Version: 2.17.52_6_gcfb9284 [ 6879.343750] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 6880.975142] Key type lgssc registered [ 6881.611163] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6886.494537] Lustre: DEBUG MARKER: Iteration 1 [ 6886.594692] LustreError: 368215:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6886.595200] LustreError: 368216:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6886.599642] LustreError: 368215:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6887.696842] Lustre: Mounted lustre-client [ 6888.302827] LustreError: 368330:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff96ae520e0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6888.385069] Lustre: Unmounted lustre-client [ 6889.585975] Key type lgssc unregistered [ 6889.735800] LNet: 368575:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6889.744554] LNetError: 368575:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6889.760896] LNet: Removed LNI 192.168.201.3@tcp [ 6890.026114] Key type .llcrypt unregistered [ 6890.027486] Key type ._llcrypt unregistered [ 6890.295612] Key type ._llcrypt registered [ 6890.296746] Key type .llcrypt registered [ 6890.528775] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6890.534856] alg: No test for adler32 (adler32-zlib) [ 6891.415702] Lustre: Lustre: Build Version: 2.17.52_6_gcfb9284 [ 6891.504746] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 6893.095134] Key type lgssc registered [ 6893.545394] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6897.691508] Lustre: DEBUG MARKER: Iteration 2 [ 6897.872432] LustreError: 369407:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6897.872803] LustreError: 369408:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6897.880432] LustreError: 369407:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6898.983222] Lustre: Mounted lustre-client [ 6899.505124] LustreError: 369524:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff96ae4cea9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6899.511514] LustreError: 369524:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6899.591839] Lustre: Unmounted lustre-client [ 6899.592884] Lustre: Skipped 1 previous similar message [ 6900.716066] Key type lgssc unregistered [ 6900.880334] LNet: 369767:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6900.883278] LNetError: 369767:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6900.890810] LNet: Removed LNI 192.168.201.3@tcp [ 6901.144157] Key type .llcrypt unregistered [ 6901.146265] Key type ._llcrypt unregistered [ 6901.439832] Key type ._llcrypt registered [ 6901.442104] Key type .llcrypt registered [ 6901.637264] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6901.644078] alg: No test for adler32 (adler32-zlib) [ 6902.528551] Lustre: Lustre: Build Version: 2.17.52_6_gcfb9284 [ 6902.637387] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 6904.231164] Key type lgssc registered [ 6904.680706] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6909.208453] Lustre: Mounted lustre-client [ 6912.087512] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 20:02:37 (1778284957) [ 6928.863142] Lustre: 371126:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778284958/real 1778284958] req@ffff96ae75eace00 x1864666917644160/t0(0) o36->lustre-MDT0000-mdc-ffff96ae4ce16800@192.168.201.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1778284974 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6928.872560] Lustre: lustre-MDT0000-mdc-ffff96ae4ce16800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6928.884916] Lustre: lustre-MDT0000-mdc-ffff96ae4ce16800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 6945.247167] Lustre: 371126:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778284974/real 1778284974] req@ffff96ae75eace00 x1864666917644160/t0(0) o36->lustre-MDT0000-mdc-ffff96ae4ce16800@192.168.201.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1778284990 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6945.271278] Lustre: lustre-MDT0000-mdc-ffff96ae4ce16800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6945.291906] Lustre: lustre-MDT0000-mdc-ffff96ae4ce16800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 6960.607159] Lustre: 371126:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778284990/real 1778284990] req@ffff96ae75eace00 x1864666917644160/t0(0) o36->lustre-MDT0000-mdc-ffff96ae4ce16800@192.168.201.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1778285006 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6960.623334] Lustre: lustre-MDT0000-mdc-ffff96ae4ce16800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6960.638507] Lustre: lustre-MDT0000-mdc-ffff96ae4ce16800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 6976.991202] Lustre: 371126:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778285006/real 1778285006] req@ffff96ae75eace00 x1864666917644160/t0(0) o36->lustre-MDT0000-mdc-ffff96ae4ce16800@192.168.201.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1778285022 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6977.002582] Lustre: lustre-MDT0000-mdc-ffff96ae4ce16800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6977.017976] Lustre: lustre-MDT0000-mdc-ffff96ae4ce16800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 6977.754763] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 20:03:42 (1778285022) [ 6983.884947] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 20:03:48 (1778285028) [ 6988.143232] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 20:03:53 (1778285033) [ 6990.647861] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 20:03:55 (1778285035) [ 6998.887829] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 20:04:03 (1778285043) [ 7013.014473] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 20:04:18 (1778285058) [ 7015.754486] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 20:04:20 (1778285060) [ 7015.875130] LustreError: 375895:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 7017.959118] LustreError: 375895:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 7020.263339] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 20:04:25 (1778285065) [ 7038.431178] Lustre: 369962:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778285067/real 1778285067] req@ffff96ae5e73c700 x1864666918681728/t0(0) o4->lustre-OST0000-osc-ffff96ae4ce16800@192.168.201.103@tcp:6/4 lens 4584/448 e 0 to 1 dl 1778285083 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7038.431208] Lustre: lustre-OST0000-osc-ffff96ae4ce16800: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7038.440489] Lustre: 369962:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 7053.791147] Lustre: 369960:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778285083/real 1778285083] req@ffff96ae81620e00 x1864666918682880/t0(0) o4->lustre-OST0000-osc-ffff96ae4ce16800@192.168.201.103@tcp:6/4 lens 4584/448 e 0 to 1 dl 1778285099 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7053.791176] Lustre: lustre-OST0000-osc-ffff96ae4ce16800: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7053.800256] Lustre: 369960:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 7053.811303] Lustre: lustre-OST0000-osc-ffff96ae4ce16800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 7070.047138] Lustre: 369960:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778285099/real 1778285099] req@ffff96ae81620e00 x1864666918682880/t0(0) o4->lustre-OST0000-osc-ffff96ae4ce16800@192.168.201.103@tcp:6/4 lens 4584/448 e 0 to 1 dl 1778285115 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7070.059464] Lustre: lustre-OST0000-osc-ffff96ae4ce16800: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7070.073149] Lustre: lustre-OST0000-osc-ffff96ae4ce16800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 7101.921288] Lustre: lustre-OST0000-osc-ffff96ae4ce16800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 7101.925122] Lustre: Skipped 1 previous similar message [ 7109.226602] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff96ae4ce16800.ost_server_uuid 50 [ 7109.804306] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff96ae4ce16800.ost_server_uuid in FULL state after 0 sec [ 7110.396717] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 7111.024548] Lustre: DEBUG MARKER: == sanityn test complete, duration 6742 sec ============== 20:05:56 (1778285156) [ 7111.599074] Lustre: DEBUG MARKER: === sanityn: start cleanup 20:05:56 (1778285156) === [ 7208.704074] LustreError: 377951:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff96ae4e4dc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7208.740099] Lustre: Unmounted lustre-client [ 7210.448055] Lustre: DEBUG MARKER: === sanityn: finish cleanup 20:07:35 (1778285255) === [ 7211.279926] LustreError: 378256:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff96ae4ce16800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7211.286715] LustreError: 378256:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 7211.331947] Lustre: Unmounted lustre-client [ 7263.680763] Key type lgssc unregistered [ 7263.815516] LNet: 378940:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7263.818607] LNetError: 378940:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7263.833191] LNet: Removed LNI 192.168.201.3@tcp [ 7264.212149] Key type .llcrypt unregistered [ 7264.213820] Key type ._llcrypt unregistered