[ 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 561932219 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 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: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001013] APIC: Switch to symmetric I/O mode setup [ 0.002000] x2apic enabled [ 0.002000] Switched APIC routing to physical x2apic. [ 0.002000] kvm-guest: setup PV IPIs [ 0.004000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.004000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.004030] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.005025] pid_max: default: 32768 minimum: 301 [ 0.006257] LSM: Security Framework initializing [ 0.008027] Yama: becoming mindful. [ 0.010005] SELinux: Initializing. [ 0.011167] *** VALIDATE selinux *** [ 0.021004] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026688] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028027] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029115] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030157] *** VALIDATE tmpfs *** [ 0.031575] *** VALIDATE proc *** [ 0.032306] *** VALIDATE cgroup *** [ 0.033016] *** VALIDATE cgroup2 *** [ 0.035006] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036231] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037024] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038037] Spectre V2 : User space: Vulnerable [ 0.039000] Speculative Store Bypass: Vulnerable [ 0.041843] debug: unmapping init [mem 0xffffffffa9059000-0xffffffffa9060fff] [ 0.043945] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044803] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045035] ... version: 2 [ 0.046023] ... bit width: 48 [ 0.047015] ... generic registers: 4 [ 0.048021] ... value mask: 0000ffffffffffff [ 0.049018] ... max period: 00007fffffffffff [ 0.050017] ... fixed-purpose events: 3 [ 0.051011] ... event mask: 000000070000000f [ 0.052519] rcu: Hierarchical SRCU implementation. [ 0.054809] smp: Bringing up secondary CPUs ... [ 0.055765] x86: Booting SMP configuration: [ 0.056033] .... node #0, CPUs: #1 #2 #3 [ 0.064119] smp: Brought up 1 node, 4 CPUs [ 0.066015] smpboot: Max logical packages: 1 [ 0.067021] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.123000] node 0 deferred pages initialised in 53ms [ 0.131366] devtmpfs: initialized [ 0.133440] x86/mm: Memory block size: 128MB [ 0.136440] gcov: version magic: 0x41383552 [ 0.146394] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.149064] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.155000] pinctrl core: initialized pinctrl subsystem [ 0.162227] [ 0.166015] ************************************************************* [ 0.183019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.192030] ** ** [ 0.199017] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.202024] ** ** [ 0.207015] ** This means that this kernel is built to expose internal ** [ 0.212043] ** IOMMU data structures, which may compromise security on ** [ 0.218015] ** your system. ** [ 0.223020] ** ** [ 0.224023] ** If you see this message and you are not debugging the ** [ 0.225000] ** kernel, report this immediately to your vendor! ** [ 0.225000] ** ** [ 0.226032] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.227000] ************************************************************* [ 0.229620] NET: Registered protocol family 16 [ 0.234206] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.238132] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.244108] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.250331] cpuidle: using governor menu [ 0.256215] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.261315] PCI: Using configuration type 1 for base access [ 0.264256] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.281490] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.282027] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.284334] cryptd: max_cpu_qlen set to 1000 [ 0.286397] ACPI: Added _OSI(Module Device) [ 0.287040] ACPI: Added _OSI(Processor Device) [ 0.289098] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.291069] ACPI: Added _OSI(Processor Aggregator Device) [ 0.299136] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.317465] ACPI: Interpreter enabled [ 0.319095] ACPI: PM: (supports S0 S3 S4 S5) [ 0.320146] ACPI: Using IOAPIC for interrupt routing [ 0.322214] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.326631] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.337180] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.340068] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.342028] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.346117] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.352720] acpiphp: Slot [2] registered [ 0.354267] acpiphp: Slot [5] registered [ 0.357286] acpiphp: Slot [6] registered [ 0.360216] acpiphp: Slot [3] registered [ 0.362154] acpiphp: Slot [4] registered [ 0.365232] acpiphp: Slot [7] registered [ 0.368170] acpiphp: Slot [8] registered [ 0.370186] acpiphp: Slot [9] registered [ 0.372181] acpiphp: Slot [10] registered [ 0.374252] acpiphp: Slot [11] registered [ 0.377192] acpiphp: Slot [12] registered [ 0.378157] acpiphp: Slot [13] registered [ 0.381595] acpiphp: Slot [14] registered [ 0.385417] acpiphp: Slot [15] registered [ 0.391569] acpiphp: Slot [16] registered [ 0.394799] acpiphp: Slot [17] registered [ 0.398179] acpiphp: Slot [18] registered [ 0.401460] acpiphp: Slot [19] registered [ 0.404484] acpiphp: Slot [20] registered [ 0.407175] acpiphp: Slot [21] registered [ 0.413167] acpiphp: Slot [22] registered [ 0.414130] acpiphp: Slot [23] registered [ 0.418170] acpiphp: Slot [24] registered [ 0.424198] acpiphp: Slot [25] registered [ 0.430167] acpiphp: Slot [26] registered [ 0.437190] acpiphp: Slot [27] registered [ 0.440174] acpiphp: Slot [28] registered [ 0.445161] acpiphp: Slot [29] registered [ 0.450188] acpiphp: Slot [30] registered [ 0.454198] acpiphp: Slot [31] registered [ 0.458096] PCI host bridge to bus 0000:00 [ 0.462091] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.473033] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.482034] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.491030] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.500035] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.504102] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.505000] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.505000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.505000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.602022] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.613000] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.613000] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.613000] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.618025] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.630869] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.639171] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.643059] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.650617] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.657025] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.694025] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.700024] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.707462] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.722020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.739021] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.758021] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.771000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.773000] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.778840] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.785024] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.790000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.792430] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.793562] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.796463] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.797344] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.803093] iommu: Default domain type: Passthrough [ 0.804570] SCSI subsystem initialized [ 0.806184] ACPI: bus type USB registered [ 0.807000] usbcore: registered new interface driver usbfs [ 0.807000] usbcore: registered new interface driver hub [ 0.807000] usbcore: registered new device driver usb [ 0.808221] pps_core: LinuxPPS API ver. 1 registered [ 0.809012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.810000] PTP clock support registered [ 0.811180] EDAC MC: Ver: 3.0.0 [ 0.813105] PCI: Using ACPI for IRQ routing [ 0.814000] NetLabel: Initializing [ 0.816011] NetLabel: domain hash size = 128 [ 0.817012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.819289] NetLabel: unlabeled traffic allowed by default [ 0.822186] vgaarb: loaded [ 0.824069] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.826011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.836000] clocksource: Switched to clocksource kvm-clock [ 1.113531] VFS: Disk quotas dquot_6.6.0 [ 1.116213] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.119202] *** VALIDATE ramfs *** [ 1.120702] *** VALIDATE hugetlbfs *** [ 1.122701] pnp: PnP ACPI init [ 1.127900] pnp: PnP ACPI: found 6 devices [ 1.145250] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.149344] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.151781] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.155806] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.159157] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 1.163071] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 1.187829] NET: Registered protocol family 2 [ 1.196447] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 1.216971] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 1.236436] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 1.251961] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 1.300349] TCP: Hash tables configured (established 65536 bind 65536) [ 1.310106] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 1.319529] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.327031] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.334464] NET: Registered protocol family 1 [ 1.340866] RPC: Registered named UNIX socket transport module. [ 1.344032] RPC: Registered udp transport module. [ 1.345809] RPC: Registered tcp transport module. [ 1.347381] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.350615] NET: Registered protocol family 44 [ 1.352851] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.355185] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.357522] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.359919] PCI: CLS 0 bytes, default 64 [ 1.361785] Unpacking initramfs... [ 4.218568] debug: unmapping init [mem 0xffff9e533cc64000-0xffff9e533ffcffff] [ 4.228509] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 4.236189] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 4.243071] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 8.286478] Initialise system trusted keyrings [ 8.445433] Key type blacklist registered [ 8.460684] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 8.507353] zbud: loaded [ 8.519546] *** VALIDATE nfs *** [ 8.523137] *** VALIDATE nfs4 *** [ 8.538369] pstore: using deflate compression [ 8.617510] Platform Keyring initialized [ 9.395869] NET: Registered protocol family 38 [ 9.399256] Key type asymmetric registered [ 9.402801] Asymmetric key parser 'x509' registered [ 9.406510] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 9.410721] io scheduler mq-deadline registered [ 9.415792] io scheduler kyber registered [ 9.420949] io scheduler bfq registered [ 9.424307] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 9.427704] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 9.441700] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 9.456872] ACPI: Power Button [PWRF] [ 9.475527] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 9.503329] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 9.543745] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 9.622448] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 9.678196] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 9.709788] Non-volatile memory driver v1.3 [ 9.715421] Linux agpgart interface v0.103 [ 9.856164] virtio_blk virtio1: [vda] 134144 512-byte logical blocks (68.7 MB/65.5 MiB) [ 9.858850] vda: detected capacity change from 0 to 68681728 [ 9.922329] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 9.925812] vdb: detected capacity change from 0 to 1073741824 [ 9.947795] libphy: Fixed MDIO Bus: probed [ 9.971962] usbcore: registered new interface driver usbserial_generic [ 9.985780] usbserial: USB Serial support registered for generic [ 10.006488] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 10.022932] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 10.032690] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 10.054666] mousedev: PS/2 mouse device common for all mice [ 10.072604] rtc_cmos 00:05: RTC can wake from S4 [ 10.078483] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 10.083271] rtc_cmos 00:05: registered as rtc0 [ 10.103786] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 10.107258] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 10.114628] intel_pstate: CPU model not supported [ 10.122779] hid: raw HID events driver (C) Jiri Kosina [ 10.125867] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 10.128672] usbcore: registered new interface driver usbhid [ 10.128679] usbhid: USB HID core driver [ 10.128795] drop_monitor: Initializing network drop monitor service [ 10.128921] Initializing XFRM netlink socket [ 10.132577] NET: Registered protocol family 10 [ 10.176885] Segment Routing with IPv6 [ 10.185820] NET: Registered protocol family 17 [ 10.193171] mpls_gso: MPLS GSO support [ 10.221457] RAS: Correctable Errors collector initialized. [ 10.229539] AVX version of gcm_enc/dec engaged. [ 10.235686] AES CTR mode by8 optimization enabled [ 10.609305] sched_clock: Marking stable (10609267465, 0)->(13139775872, -2530508407) [ 10.644107] registered taskstats version 1 [ 10.651458] Loading compiled-in X.509 certificates [ 10.653667] zswap: loaded using pool lzo/zbud [ 10.797763] Key type big_key registered [ 10.833035] Key type encrypted registered [ 10.843497] ima: No TPM chip found, activating TPM-bypass! [ 10.853726] ima: Allocated hash algorithm: sha1 [ 10.868674] ima: No architecture policies found [ 10.870323] evm: Initialising EVM extended attributes: [ 10.879642] evm: security.selinux [ 10.880746] evm: security.ima [ 10.885858] evm: security.capability [ 10.889887] evm: HMAC attrs: 0x1 [ 10.899839] rtc_cmos 00:05: setting system clock to 2026-05-09 17:14:50 UTC (1778346890) [ 10.920252] debug: unmapping init [mem 0xffffffffaa003000-0xffffffffaa1fffff] [ 10.943395] debug: unmapping init [mem 0xffffffffa8d82000-0xffffffffa9058fff] [ 10.955609] Write protecting the kernel read-only data: 28672k [ 10.970626] debug: unmapping init [mem 0xffffffffa7403000-0xffffffffa75fffff] [ 10.978860] debug: unmapping init [mem 0xffffffffa7d14000-0xffffffffa7dfffff] [ 11.121824] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.153912] systemd[1]: Detected virtualization kvm. [ 11.159421] systemd[1]: Detected architecture x86-64. [ 11.163369] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 11.250940] systemd[1]: No hostname configured. [ 11.254488] systemd[1]: Set hostname to . [ 11.259069] random: systemd: uninitialized urandom read (16 bytes read) [ 11.262410] systemd[1]: Initializing machine ID from random generator. [ 11.361072] random: ln: uninitialized urandom read (6 bytes read) [ 11.605484] random: systemd: uninitialized urandom read (16 bytes read) [ 11.609895] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 11.633370] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 11.651044] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local File Systems. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Paths. Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 14.499522] device-mapper: uevent: version 1.0.3 [ 14.503345] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 18.541680] virtio_net virtio0 ens2: renamed from eth0 [ 18.941595] scsi host0: ata_piix [ 19.245388] scsi host1: ata_piix [ 19.254876] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 19.261164] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 22.596765] random: fast init done [ 24.417445] random: crng init done [ 24.435393] random: 7 urandom warning(s) missed due to ratelimiting [ 27.069688] dracut-initqueue[584]: 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. [ 30.107515] 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... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 34.598870] printk: systemd: 26 output lines suppressed due to ratelimiting [ 35.674963] SELinux: Disabled at runtime. [ 35.919263] 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) [ 35.946349] systemd[1]: Detected virtualization kvm. [ 35.950594] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 38.549943] systemd[1]: initrd-switch-root.service: Succeeded. [ 38.573464] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 38.607483] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 38.623594] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 38.642849] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 38.671740] systemd[1]: Starting Journal Service... Starting Journal Service... [ 38.748038] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting POSIX Message Queue File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-serial\x2dgetty.slice. [ 39.374845] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Apply Kernel Variables... [ OK ] Listening on Process Core Dump Socket. Mounting Kernel Debug File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target RPC Port Mapper. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Journal Service. Starting Flush Journal to Persistent Storage... Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ OK ] Reached target Swap. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 41.104035] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 42.818574] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 43.152143] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 44.054392] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 44.271834] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (9s / no limit) [** ] A start job is running for Configur…-only root support (9s / no limit) [*** ] A start job is running for Configur…only root support (10s / no limit) [ *** ] 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)[ 50.145460] Key type dns_resolver registered [ **] 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)[ 51.559375] NFS: Registering the id_resolver key type [ 51.570539] Key type id_resolver registered [ 51.577375] Key type id_legacy registered [ ***] 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) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started Login Service. [ OK ] Started OpenSSH server daemon. [ 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 Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg435-client login: [ 122.843709] libcfs: loading out-of-tree module taints kernel. [ 122.920238] Key type ._llcrypt registered [ 122.927273] Key type .llcrypt registered [ 123.298556] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 123.314407] alg: No test for adler32 (adler32-zlib) [ 124.508839] Lustre: Lustre: Build Version: 2.17.52_4_g786747d [ 125.091358] LNet: Added LNI 192.168.204.35@tcp [8/256/0/180] [ 126.815185] Key type lgssc registered [ 128.200498] Lustre: Echo OBD driver; http://www.lustre.org/ [ 228.033050] Lustre: Mounted lustre-client [ 231.568749] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 252.246222] Lustre: DEBUG MARKER: oleg435-client.virtnet: executing check_logdir /tmp/testlogs/ [ 253.919575] Lustre: lustre-OST0000-osc-ffff9e53829a2000: disconnect after 24s idle [ 256.226583] Lustre: DEBUG MARKER: oleg435-client.virtnet: executing yml_node [ 259.551494] Lustre: DEBUG MARKER: Client: 2.17.52.4 [ 261.218790] Lustre: DEBUG MARKER: MDS: 2.17.52.4 [ 262.895412] Lustre: DEBUG MARKER: OSS: 2.17.52.4 [ 263.974138] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Sat May 9 13:19:02 EDT 2026 [ 266.279062] hrtimer: interrupt took 2961653 ns [ 276.869928] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 277.830978] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 278.944889] Lustre: DEBUG MARKER: === sanityn: start setup 13:19:17 (1778347157) === [ 279.404201] Lustre: Mounted lustre-client [ 281.826482] Lustre: DEBUG MARKER: oleg435-client.virtnet: executing check_config_client /mnt/lustre [ 292.015356] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 298.157420] Lustre: DEBUG MARKER: === sanityn: finish setup 13:19:36 (1778347176) === [ 299.522204] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 13:19:38 (1778347178) [ 300.000458] Lustre: lustre-OST0000-osc-ffff9e5383546800: disconnect after 20s idle [ 300.011237] Lustre: Skipped 1 previous similar message [ 304.467427] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 13:19:43 (1778347183) [ 308.754904] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 13:19:47 (1778347187) [ 313.276355] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 13:19:51 (1778347191) [ 317.866576] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 13:19:56 (1778347196) [ 322.423428] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 13:20:01 (1778347201) [ 327.290547] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 13:20:05 (1778347205) [ 333.198559] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 13:20:11 (1778347211) [ 334.294150] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 335.812978] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 13:20:14 (1778347214) [ 341.386548] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 13:20:20 (1778347220) [ 346.599813] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 13:20:25 (1778347225) [ 352.576767] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 13:20:31 (1778347231) [ 357.346385] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 13:20:35 (1778347235) [ 361.737834] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 13:20:40 (1778347240) [ 365.967451] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 13:20:44 (1778347244) [ 370.137711] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 13:20:48 (1778347248) [ 375.459569] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 13:20:54 (1778347254) [ 379.783578] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 13:20:58 (1778347258) [ 385.159533] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 13:21:03 (1778347263) [ 390.015694] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 13:21:08 (1778347268) [ 394.908706] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 13:21:13 (1778347273) [ 395.333727] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 522.430963] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 13:23:21 (1778347401) [ 525.552617] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 13:23:24 (1778347404) [ 527.999781] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 13:23:27 (1778347407) [ 530.399637] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 13:23:29 (1778347409) [ 532.851818] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 13:23:31 (1778347411) [ 535.521333] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 13:23:34 (1778347414) [ 536.184079] Lustre: DEBUG MARKER: chmod [ 538.623731] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 13:23:37 (1778347417) [ 560.525953] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7527424KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 568.365695] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 13:24:07 (1778347447) [ 577.714945] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 13:24:16 (1778347456) [ 583.700789] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 13:24:22 (1778347462) [ 584.439956] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 584.949797] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 13:24:24 (1778347464) [ 596.230230] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 13:24:35 (1778347475) [ 598.424196] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 13:24:37 (1778347477) [ 598.806604] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.846937] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.879316] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.910198] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.951671] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.976516] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.002202] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.027509] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.051776] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.072873] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.093560] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.117233] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.140060] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.162279] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.188981] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.209530] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.231477] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.251182] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.270619] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.290219] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.310768] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.332284] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.353140] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.374080] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.395549] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.415411] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.439889] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.460804] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.487825] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.514297] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.534639] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.560654] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.588452] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.611618] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.633257] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.656627] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.682509] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.703499] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.732274] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.762446] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.784609] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.804275] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.829624] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.856888] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.877769] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.900301] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.922467] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.942262] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.962739] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.986961] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.007509] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.027953] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.048030] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.066940] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.086565] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.106730] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.128535] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.149447] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.169236] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.188955] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.212700] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.233589] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.255316] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.275346] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.296412] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.319462] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.342559] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.364142] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.386302] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.407592] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.428610] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.449546] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.470911] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.492151] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.512911] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.532385] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.550985] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.569434] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.589155] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.609910] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.629369] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.648482] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.667701] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.687938] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.708448] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.728238] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.750183] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.771606] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.791030] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.812778] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.836855] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.856314] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.879341] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.902951] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.925121] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.945858] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.971257] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.991901] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.014309] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.034680] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.054314] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.078932] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.098140] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.118033] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.140627] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.162415] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.186020] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.207089] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.227502] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.247917] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.269878] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.290477] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.311183] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.333203] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.354590] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.375685] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.396774] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.416927] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.436283] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.456128] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.476673] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.497625] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.524911] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.543970] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.564079] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.583336] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.602315] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.624744] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.652392] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.679799] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.700738] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.720527] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.744870] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.767553] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.788663] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.811190] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.831919] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.852871] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.874190] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.894334] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.918241] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.939828] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.961758] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.983909] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.005620] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.031909] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.059052] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.086755] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.112145] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.138449] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.164049] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.184880] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.205214] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.225603] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.253101] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.273645] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.294481] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.318110] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.338521] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.357490] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.382134] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.402528] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.426138] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.447491] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.467602] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.487202] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.511979] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.536454] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.565100] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.598186] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.620636] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.650616] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.673048] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.695146] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.718660] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.739191] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.763168] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.786487] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.816986] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.843809] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.869166] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.892466] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.918150] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.943819] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.977116] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.000324] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.023387] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.047575] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.070773] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.091853] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.114697] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.136293] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.156767] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.181892] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.203030] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.226309] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.252124] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.272791] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.291977] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.312182] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.330847] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.349997] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.369172] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.393117] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.415065] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.435076] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.454417] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.473693] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.494370] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.515414] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.535894] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.564807] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.595482] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.628911] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.650253] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.675020] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.702123] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.723872] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.744442] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.764605] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.785163] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.806243] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.827624] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.851342] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.872234] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.892772] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.914693] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.934330] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.956883] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.984623] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.022089] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.045444] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.072612] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.098555] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.122940] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.147903] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.174945] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.202573] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.232034] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.251675] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.274667] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.300341] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.326857] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.350611] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.372638] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.400849] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.420483] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.444450] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.465604] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.486749] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.508938] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.529813] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.552698] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.572557] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.595641] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.616220] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.651438] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.676151] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.699932] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.730566] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.756172] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.777903] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.803251] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.829750] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.859632] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.886481] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.912559] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.935191] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.958428] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.985892] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.012095] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.035671] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.059280] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.082130] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.116471] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.142734] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.176976] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.205988] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.235842] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.259888] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.283475] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.318552] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.349844] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.376625] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.400342] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.424136] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.450336] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.473349] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.495594] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.524095] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.551056] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.581817] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.613732] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.637957] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.666039] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.689428] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.714127] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.735607] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.759704] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.784412] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.806902] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.833517] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.855940] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.879300] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.909834] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.933269] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.963617] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.992979] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.017212] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.041645] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.067374] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.089758] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.116806] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.139991] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.166453] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.194206] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.216191] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.243529] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.268541] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.289861] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.312990] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.341354] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.366227] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.388522] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.412270] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.436647] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.472530] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.507322] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.534723] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.559741] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.591124] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.618286] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.642213] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.667272] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.691972] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.720711] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.749402] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.781907] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.808150] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.830702] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.857927] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.881455] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.907636] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.930961] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.958144] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.984875] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.010923] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.037299] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.068983] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.089557] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.122948] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.157665] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.192597] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.225754] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.257461] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.287040] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.313589] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.341160] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.364413] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.387168] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.415647] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.446202] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.472941] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.499597] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.523488] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.555432] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.582322] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.611383] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.639586] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.666214] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.693159] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.721958] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.748353] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.775305] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.802888] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.829670] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.857701] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.885744] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.912391] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.940380] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.967904] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.993057] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.015735] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.043228] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.064938] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.092040] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.118828] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.145543] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.172591] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.199384] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.227432] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.253679] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.276108] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.301224] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.325359] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.346456] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.373040] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.401580] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.429042] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.455254] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.481652] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.508278] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.529945] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.557890] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.586555] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.613022] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.635160] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.655759] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.682598] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.710413] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.739121] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.766307] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.789278] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.809793] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.830934] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.851205] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.872069] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.892140] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.913851] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.934142] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.956448] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.978989] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.005650] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.032633] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.053920] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.076644] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.098190] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.117802] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.137971] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.160627] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.180292] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.200437] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.218775] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.238502] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.257846] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.277634] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.297202] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.317290] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.338629] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.362134] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.383344] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.403556] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.423750] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.442741] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.461869] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.482080] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.500430] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.520374] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.540323] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.558844] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.579215] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.603448] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.628392] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.649687] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.671597] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.691897] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.714533] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.734954] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.757661] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.785085] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.807912] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.826817] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.848196] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.868983] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.891499] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.912793] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.934477] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.956180] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.976639] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.997501] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.022299] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.049791] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.071538] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.093270] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.117408] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.141366] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.165737] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.187914] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.209042] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.234568] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.255529] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.279895] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.302766] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.327122] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.346969] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.367057] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.387336] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.407165] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.429820] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.452756] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.474501] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.500551] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.527411] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.549398] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.571991] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.597874] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.618739] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.642798] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.669076] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.697105] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.721180] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.745930] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.770722] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.792500] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.812537] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.833922] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.860880] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.888107] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.911275] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.932931] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.951868] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.980254] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.009646] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.032859] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.057786] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.078308] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.100410] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.123555] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.144409] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.164471] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.185159] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.206016] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.227083] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.248157] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.273995] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.296681] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.317485] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.337688] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.360454] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.382948] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.404195] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.426371] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.446652] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.471121] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.494775] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.516629] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.538638] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.560395] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.581661] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.605516] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.624986] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.649644] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.670625] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.697037] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.719663] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.740187] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.759922] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.781143] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.806393] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.830916] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.853249] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.875587] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.898237] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.919305] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.941047] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.962828] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 611.982706] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.004628] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.025611] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.046140] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.067647] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.088575] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.115115] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.137936] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.159672] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.180689] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.201839] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.224233] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.247669] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.270963] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.294662] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.317225] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.338600] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.359830] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.381039] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.401912] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.423354] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.448201] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.468162] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.488781] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.513210] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.538285] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.565563] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.591616] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.617983] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.640336] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.664928] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.685465] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.709159] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.730288] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.750751] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.777505] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.804144] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.829898] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.852346] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.879450] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.900797] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.923147] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.943571] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.964418] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 612.987052] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.008870] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.030491] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.051318] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.072803] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.093707] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.114709] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.136340] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.161746] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.181557] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.200815] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.224854] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.251133] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.275225] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.297064] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.317258] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.342429] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.366235] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.391867] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.416932] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.444647] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.472122] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.498380] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.521841] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.542905] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.564023] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.586982] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.610781] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.635804] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.656527] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.678863] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.700130] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.725801] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.752893] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.779362] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.800634] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.827828] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.854968] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.881992] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.908053] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.929752] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.949745] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.970805] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 613.993976] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.014407] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.038302] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.060400] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.083830] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.108889] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.128816] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.149613] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.170071] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.191447] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.217059] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.240874] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.267297] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.291136] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.317529] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.341273] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.364853] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.391673] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.420638] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.443525] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.470157] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.492508] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.517774] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.544776] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.571017] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.594342] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.616556] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.639735] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.662296] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.686488] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.711980] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.737364] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.763038] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.786187] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.815361] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.838680] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.862193] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.886686] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.910108] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.931371] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.953184] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.976806] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.001744] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.022276] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.043227] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.066588] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.088787] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.109758] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.131503] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.157161] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.181173] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.205293] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.232578] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.258699] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.284893] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.306828] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.327699] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.353916] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.376537] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.405163] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.434965] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.464082] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.491545] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.518787] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.541920] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.563464] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.586479] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.606708] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.627860] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.650642] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.673608] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.695312] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.715539] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.736077] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.759400] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.787106] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.809033] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.831741] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.851090] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.872160] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.893145] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.914218] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.935569] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.956287] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.976863] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 615.997721] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.023863] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.046598] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.068235] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.090551] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.116775] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.139241] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.164252] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.189817] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.215601] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.243493] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.271208] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.293767] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.317422] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.344367] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.364605] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.389060] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.410967] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.431045] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.451863] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.475323] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.498495] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.520174] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.540784] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.564725] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.587045] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.609490] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.632590] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.654433] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.675415] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.696731] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.716924] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.738935] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.759522] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.781269] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.802450] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.822741] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.845497] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.868354] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.889164] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.909511] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.932143] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.957044] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 616.980845] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.002701] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.023609] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.048123] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.072804] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.097616] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.119400] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.139715] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.160342] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.181671] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.201953] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.222297] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.242439] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.262817] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.283682] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.305160] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.324581] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.343915] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.371053] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.390862] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.411631] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.431704] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.440103] Lustre: lustre-OST0001-osc-ffff9e5383546800: disconnect after 21s idle [ 617.442775] Lustre: Skipped 1 previous similar message [ 617.451355] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.473237] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.495244] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.516826] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.536784] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.560068] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.584969] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.611424] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.637743] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.663677] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.689442] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.716503] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.742731] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.762728] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.781283] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.800904] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.822187] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.843197] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.863573] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.891231] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.920407] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.948561] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.972891] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 617.993242] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.013627] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.035084] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.055540] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.078420] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.101468] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.122296] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.145508] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.167406] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.188054] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.209446] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.231530] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.253641] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.275887] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.299542] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.323024] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.345851] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.369623] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.394806] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.418419] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.441581] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 618.459449] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 620.723993] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 13:24:59 (1778347499) [ 620.852540] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 620.867365] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 620.892355] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 620.913167] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 620.929056] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 620.948833] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 620.964158] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 620.999828] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.013823] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.102069] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.180441] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.202609] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.223839] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.242729] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.258176] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.273156] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.288149] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.302402] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.319765] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.335350] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.417889] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.435772] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.461459] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.476837] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.492192] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.506467] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.526317] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.547786] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.808956] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.823439] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.843531] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.858584] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.882038] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.920497] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 621.941489] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.103288] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.119149] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.139952] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.259648] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.283308] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.312661] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.335153] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.358513] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.379583] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.401881] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.423592] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.443516] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.458469] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.481793] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.503781] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.526917] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.566716] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.586280] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.600729] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.618544] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.640737] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.660920] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.684166] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.702856] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.717756] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.734893] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.749205] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.767021] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.784778] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.802836] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.819405] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.919618] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.939547] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.962254] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 622.988122] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.016047] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.040252] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.064697] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.089340] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.114880] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.140478] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.199065] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.268138] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.350150] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.371642] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.393571] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.430434] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.474221] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.594173] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.628917] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.654659] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.761547] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.780230] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.795147] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.864692] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.880617] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.900302] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.917701] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 623.939227] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.125558] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.149948] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.172559] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.191126] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.206722] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.275081] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.290254] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.305428] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.322970] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.342098] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.362792] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.388708] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.409185] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.448463] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.464890] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.481193] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.503222] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.527151] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.547327] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.566186] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.645128] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.834910] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.852204] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.867307] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.884655] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.905535] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.921889] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.943571] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.964468] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.979741] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 624.994100] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.013457] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.139638] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.218465] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.234257] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.250326] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.303643] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.319387] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.335397] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.350875] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.384296] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.406159] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.426466] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.442706] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.458594] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.474378] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.490117] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.505380] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.520184] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.537192] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.556568] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.610814] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.631324] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.650837] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.664331] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.677997] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.697417] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.772445] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.828100] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.853960] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.875856] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.894564] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.914516] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 625.978949] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.001046] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.018526] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.171858] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.189876] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.205941] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.227283] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.247674] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.268336] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.289815] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.313760] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.332130] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.416575] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.438793] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.460851] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.476890] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.491749] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.506821] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.522207] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.538368] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.554682] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.579810] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.599694] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.620972] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.640563] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.742994] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.766443] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.787171] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.801871] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.816079] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.831671] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.912951] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.927738] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 626.942766] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.020050] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.043758] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.069628] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.114940] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.161418] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.183157] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.199082] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.218255] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.239469] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.259058] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.329980] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.504057] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.540879] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.563425] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.586019] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.605361] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.621073] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.640335] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.656176] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.672684] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.694386] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.718695] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.737135] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.758177] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.798432] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.816032] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.833918] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.850029] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.865188] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.881899] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.905048] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.928069] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.979025] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 627.999579] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.082064] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.105166] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.171980] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.193294] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.210505] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.230411] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.248315] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.268175] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.290913] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.374352] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.398347] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.420174] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.444530] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.465468] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.483651] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.507898] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.538626] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.560522] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.577844] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.597601] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.703082] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.723229] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.739555] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.753177] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.772895] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.794680] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.809858] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 628.833851] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.045064] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.066132] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.083431] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.103795] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.121420] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.196439] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.216601] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.235815] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.251735] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.327129] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.345882] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.367203] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.383989] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.430072] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.447793] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.462500] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.642825] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.673878] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.692423] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.710365] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.732094] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.752465] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.766169] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.781633] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.810569] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.830915] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.847542] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 629.870772] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.017713] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.102097] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.126067] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.209664] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.232509] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.256903] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.279397] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.301480] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.317944] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.333106] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.348405] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.364259] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.380562] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.399383] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.423277] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.445701] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.467910] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.546785] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.561401] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.576664] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.594620] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.648064] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.672504] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.699056] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.718136] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.738074] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.756951] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.771414] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.788748] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.824104] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.844018] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.867500] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.888986] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.912455] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 630.992772] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.013110] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.029750] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.044933] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.060838] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.078408] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.094798] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.113953] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.128580] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.143025] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.157658] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.173272] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.187511] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.209132] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.308749] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.327161] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.342297] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.357846] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.405418] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.427634] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.447664] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.482365] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.503770] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.524552] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.546534] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.626155] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.641446] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.678500] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.701645] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.723973] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.881995] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.904566] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.922931] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.937516] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.957232] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 631.980136] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.003394] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.026519] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.091888] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.112461] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.130454] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.165402] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.185903] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.205519] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.294070] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.314134] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.331991] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.351608] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.373175] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.394361] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.414706] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.431428] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.452250] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.473685] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.492651] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.514061] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.528677] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.546420] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.666294] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.681522] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.751878] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.772134] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.795682] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.822090] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.844643] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.861220] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.877170] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.901521] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.919654] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.936965] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.961647] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 632.984336] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.007247] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.028981] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.046809] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.144203] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.163987] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.191935] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.217332] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.234309] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.250350] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.313077] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.329397] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.355913] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.378463] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.492375] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.517952] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.541404] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.816829] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.839104] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.899704] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 633.914442] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.179690] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.201921] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.223369] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.240264] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.261824] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.283658] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.301181] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.322433] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.381553] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.402394] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.465792] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.483214] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.505938] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.567403] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.589216] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.608775] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.623373] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.639169] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.658084] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.674498] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.845078] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.860448] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.879734] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.894448] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.909717] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.924554] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.939220] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.956318] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 634.977486] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.002779] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.021437] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.163315] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.179692] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.199636] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.221956] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.247504] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.265179] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.298031] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.312807] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.330047] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.352629] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.418846] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.443580] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.470347] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.486267] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.552555] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.590338] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.605749] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.622667] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.648361] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.733768] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.761661] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.778095] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.795813] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.816241] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.833476] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.856136] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.878396] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.899797] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.920672] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 635.936589] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.077105] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.093048] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.108814] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.129577] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.149775] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.414236] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.569443] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.585055] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.599592] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.617303] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.638829] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.657219] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.857471] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.968484] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 636.989474] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.009505] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.025054] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.047220] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.073700] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.097376] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.120634] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.146022] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.164609] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.183851] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.206902] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.224383] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.246513] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.269819] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.295692] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.634656] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.652811] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.695254] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.710839] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.725455] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.766216] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.786537] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.814033] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.839187] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.857705] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.878178] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.898140] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.920854] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.943341] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.965947] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 637.984145] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.002348] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.018215] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.038666] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.059783] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.076881] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.098830] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.124520] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.149048] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.173030] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.199432] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.223573] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.247517] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.271548] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.298910] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.321958] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.346260] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.370178] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.394240] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.417044] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.709410] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.730283] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.748082] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.765657] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.788385] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.803915] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.821314] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.841835] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.857159] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.880633] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.900728] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.917311] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.957344] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.977533] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 638.995727] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.016811] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.031556] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.046689] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.063351] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.084398] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.103885] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.125134] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.408265] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.430812] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.455895] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.587158] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.611573] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.708910] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.727520] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.742383] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.757932] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.775894] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.796172] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.814228] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.829247] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.845940] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.865593] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.886723] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.902211] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.921932] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.941981] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 639.960745] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.020370] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.052319] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.132068] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.151696] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.174789] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.199103] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.279793] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.302758] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.326664] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.351041] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.375258] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.397597] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.416949] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.440932] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.522501] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.538363] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.553555] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.570044] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.599229] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.620429] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.638543] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 640.765122] rw_seq_cst_vs_d (30056): drop_caches: 3 [ 643.040154] Lustre: lustre-OST0000-osc-ffff9e53829a2000: disconnect after 20s idle [ 643.156783] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 13:25:22 (1778347522) [ 645.299905] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 13:25:24 (1778347524) [ 647.319830] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 13:25:26 (1778347526) [ 656.105753] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 13:25:35 (1778347535) [ 656.367276] bash (32498): drop_caches: 3 [ 659.472503] bash (32498): drop_caches: 3 [ 662.525873] bash (32498): drop_caches: 3 [ 666.083457] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 13:25:45 (1778347545) [ 670.508389] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 13:25:49 (1778347549) [ 681.891339] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 13:26:01 (1778347561) [ 682.726042] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 683.243770] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 13:26:02 (1778347562) [ 685.361297] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 13:26:04 (1778347564) [ 687.369915] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 13:26:06 (1778347566) [ 699.359220] Lustre: lustre-OST0001-osc-ffff9e5383546800: disconnect after 21s idle [ 699.361829] Lustre: Skipped 1 previous similar message [ 709.599245] Lustre: lustre-OST0001-osc-ffff9e53829a2000: disconnect after 22s idle [ 709.601698] Lustre: Skipped 2 previous similar messages [ 750.846611] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 13:27:10 (1778347630) [ 752.879127] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 13:27:12 (1778347632) [ 754.864612] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 13:27:14 (1778347634) [ 757.065196] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 13:27:16 (1778347636) [ 757.582220] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 758.090632] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 13:27:17 (1778347637) [ 761.111529] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 13:27:20 (1778347640) [ 765.055360] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 13:27:24 (1778347644) [ 768.041192] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 768.570263] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 13:27:27 (1778347647) [ 772.560991] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 13:27:31 (1778347651) [ 772.653121] Lustre: *** cfs_fail_loc=314, val=0*** [ 773.663177] Lustre: *** cfs_fail_loc=314, val=0*** [ 773.664624] Lustre: Skipped 2 previous similar messages [ 775.521363] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 13:27:34 (1778347654) [ 791.566573] Lustre: *** cfs_fail_loc=314, val=0*** [ 793.898664] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 13:27:53 (1778347673) [ 793.957149] LustreError: 42418:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 794.972900] LustreError: lustre-OST0000-osc-ffff9e5383546800: operation ldlm_enqueue to node 192.168.204.135@tcp failed: rc = -107 [ 794.975884] Lustre: lustre-OST0000-osc-ffff9e5383546800: Connection to lustre-OST0000 (at 192.168.204.135@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 794.980861] LustreError: lustre-OST0000-osc-ffff9e5383546800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 794.986325] Lustre: lustre-OST0000-osc-ffff9e5383546800: Connection restored to 192.168.204.135@tcp (at 192.168.204.135@tcp) [ 796.975089] LustreError: 42418:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 798.978110] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 13:27:58 (1778347678) [ 801.548273] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 13:28:00 (1778347680) [ 804.725837] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 805.318666] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 13:28:04 (1778347684) [ 805.876631] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 806.462674] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 13:28:05 (1778347685) [ 806.933297] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 807.449709] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 13:28:06 (1778347686) [ 807.918556] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 808.437696] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 13:28:07 (1778347687) [ 808.960421] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 809.536776] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 13:28:08 (1778347688) [ 827.359289] Lustre: lustre-OST0000-osc-ffff9e5383546800: disconnect after 24s idle [ 857.013454] Lustre: lustre-OST0001-osc-ffff9e53829a2000: Connection to lustre-OST0001 (at 192.168.204.135@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 857.020821] LustreError: lustre-OST0001-osc-ffff9e5383546800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 857.026474] LustreError: lustre-OST0001-osc-ffff9e53829a2000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 857.026839] Lustre: lustre-OST0001-osc-ffff9e5383546800: Connection restored to 192.168.204.135@tcp (at 192.168.204.135@tcp) [ 857.034531] Lustre: Skipped 1 previous similar message [ 878.559185] Lustre: lustre-OST0001-osc-ffff9e53829a2000: disconnect after 22s idle [ 878.560641] Lustre: lustre-OST0000-osc-ffff9e53829a2000: Connection to lustre-OST0000 (at 192.168.204.135@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 878.566398] Lustre: Skipped 1 previous similar message [ 878.568640] LustreError: lustre-OST0000-osc-ffff9e53829a2000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 878.572572] Lustre: lustre-OST0000-osc-ffff9e53829a2000: Connection restored to 192.168.204.135@tcp (at 192.168.204.135@tcp) [ 888.644050] Lustre: DEBUG MARKER: oleg435-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e53829a2000.ost_server_uuid 50 [ 889.180983] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e53829a2000.ost_server_uuid in FULL state after 0 sec [ 890.401701] Lustre: DEBUG MARKER: oleg435-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e53829a2000.ost_server_uuid 50 [ 890.906659] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e53829a2000.ost_server_uuid in IDLE state after 0 sec [ 892.485729] Lustre: DEBUG MARKER: oleg435-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e53829a2000.ost_server_uuid 50 [ 892.971655] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e53829a2000.ost_server_uuid in FULL state after 0 sec [ 894.191343] Lustre: DEBUG MARKER: oleg435-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e53829a2000.ost_server_uuid 50 [ 894.676473] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e53829a2000.ost_server_uuid in IDLE state after 0 sec [ 897.774278] Lustre: DEBUG MARKER: oleg435-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e53829a2000.ost_server_uuid 50 [ 898.263113] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e53829a2000.ost_server_uuid in FULL state after 0 sec [ 899.367228] Lustre: DEBUG MARKER: oleg435-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e53829a2000.ost_server_uuid 50 [ 899.830757] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e53829a2000.ost_server_uuid in IDLE state after 0 sec [ 900.392400] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 13:29:39 (1778347779) [ 901.243422] Lustre: DEBUG MARKER: Race attempt 0 [ 902.298411] Lustre: *** cfs_fail_loc=317, val=2147484440*** [ 902.300272] LustreError: 49169:0:(ldlm_lock.c:915:ldlm_lock_decref_internal()) cfs_race id 318 sleeping [ 902.301190] LustreError: 5544:0:(ldlm_lockd.c:2457:ldlm_callback_handler()) cfs_fail_race id 318 waking [ 902.305242] LustreError: 49169:0:(ldlm_lock.c:915:ldlm_lock_decref_internal()) cfs_fail_race id 318 awake: rc=4997 [ 902.308624] LustreError: 49169:0:(mdc_request.c:1498:mdc_read_page()) lustre-MDT0000-mdc-ffff9e53829a2000: [0x200000401:0x8c:0x0] lock enqueue fails: rc = -4 [ 902.312824] LustreError: 49169:0:(statahead.c:1807:is_first_dirent()) lustre: reading dir [0x200000401:0x8c:0x0] at 0 stat_pid = 49169 : rc = -4 [ 902.822397] Lustre: DEBUG MARKER: Wait for 49034 49169 for 60 sec... [ 964.973116] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 13:30:44 (1778347844) [ 970.090360] Lustre: DEBUG MARKER: start test - cycle (0) [ 995.866681] Lustre: DEBUG MARKER: start test - cycle (1) [ 996.319115] Lustre: lustre-OST0001-osc-ffff9e5383546800: disconnect after 22s idle [ 996.320957] Lustre: Skipped 2 previous similar messages [ 1016.004362] Lustre: DEBUG MARKER: start test - cycle (2) [ 1036.189750] Lustre: DEBUG MARKER: start test - cycle (3) [ 1056.554494] Lustre: DEBUG MARKER: start test - cycle (4) [ 1078.119134] Lustre: DEBUG MARKER: start test - cycle (5) [ 1098.333791] Lustre: DEBUG MARKER: start test - cycle (6) [ 1118.610948] Lustre: DEBUG MARKER: start test - cycle (7) [ 1138.940951] Lustre: DEBUG MARKER: start test - cycle (8) [ 1159.288494] Lustre: DEBUG MARKER: start test - cycle (9) [ 1179.501134] Lustre: DEBUG MARKER: start test - cycle (10) [ 1203.188493] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 13:34:42 (1778348082) [ 1206.239143] Lustre: lustre-OST0001-osc-ffff9e5383546800: disconnect after 23s idle [ 1219.834684] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 13:34:58 (1778348098) [ 1222.145874] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 13:35:01 (1778348101) [ 1225.294762] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 13:35:04 (1778348104) [ 1228.551279] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 13:35:07 (1778348107) [ 1228.642430] Lustre: *** cfs_fail_loc=411, val=0*** [ 1230.550538] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1231.084310] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 13:35:10 (1778348110) [ 1239.400575] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 13:35:18 (1778348118) [ 1247.390735] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 13:35:26 (1778348126) [ 1255.450264] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 13:35:34 (1778348134) [ 1262.933851] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 13:35:42 (1778348142) [ 1268.351986] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 13:35:47 (1778348147) [ 1273.486345] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 13:35:52 (1778348152) [ 1279.084341] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 13:35:58 (1778348158) [ 1285.072166] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 13:36:04 (1778348164) [ 1290.889260] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 13:36:10 (1778348170) [ 1296.356261] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 13:36:15 (1778348175) [ 1301.863981] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 13:36:21 (1778348181) [ 1307.404897] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 13:36:26 (1778348186) [ 1928.159239] Lustre: lustre-OST0000-osc-ffff9e5383546800: disconnect after 23s idle [ 1928.163596] Lustre: Skipped 5 previous similar messages [ 2031.561382] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 13:48:30 (1778348910) [ 2037.401427] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 13:48:36 (1778348916) [ 2043.052394] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 13:48:42 (1778348922) [ 2048.536385] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 13:48:47 (1778348927) [ 2053.841496] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 13:48:52 (1778348932) [ 2059.308721] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 13:48:58 (1778348938) [ 2064.814875] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 13:49:03 (1778348943) [ 2070.482481] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 13:49:09 (1778348949) [ 2076.109509] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 13:49:15 (1778348955) [ 2093.563749] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 13:49:32 (1778348972) [ 2098.545416] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 13:49:37 (1778348977) [ 2103.449185] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 13:49:42 (1778348982) [ 2108.312262] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 13:49:47 (1778348987) [ 2113.373359] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 13:49:52 (1778348992) [ 2118.257100] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 13:49:57 (1778348997) [ 2123.053648] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 13:50:02 (1778349002) [ 2127.843245] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 13:50:07 (1778349007) [ 2128.296881] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 2128.804813] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 13:50:07 (1778349007) [ 2172.834811] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 13:50:51 (1778349051) [ 2635.181411] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 13:58:34 (1778349514) [ 2640.226086] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 13:58:39 (1778349519) [ 2645.990583] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 13:58:45 (1778349525) [ 2652.331297] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 13:58:51 (1778349531) [ 2658.427782] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 13:58:57 (1778349537) [ 2664.436935] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 13:59:03 (1778349543) [ 2670.097860] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 13:59:09 (1778349549) [ 2675.891519] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 13:59:15 (1778349555) [ 2682.652079] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 13:59:21 (1778349561) [ 2683.372633] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 2684.218738] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 13:59:23 (1778349563) [ 2696.160162] Lustre: lustre-OST0001-osc-ffff9e53829a2000: disconnect after 20s idle [ 2696.162755] Lustre: Skipped 6 previous similar messages [ 2718.058289] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 13:59:57 (1778349597) [ 2723.522778] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 14:00:02 (1778349602) [ 2729.068695] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 14:00:08 (1778349608) [ 2734.702620] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 14:00:13 (1778349613) [ 2741.053848] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 14:00:19 (1778349619) [ 2746.661529] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 14:00:25 (1778349625) [ 2751.740612] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 14:00:30 (1778349630) [ 2756.595569] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 14:00:35 (1778349635) [ 2757.189016] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 2757.848321] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 14:00:36 (1778349636) [ 3248.179525] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 14:08:47 (1778350127) [ 3254.567942] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 14:08:53 (1778350133) [ 3260.532705] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 14:08:59 (1778350139) [ 3266.235948] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 14:09:05 (1778350145) [ 3272.680453] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 14:09:11 (1778350151) [ 3278.766700] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 14:09:17 (1778350157) [ 3284.901849] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 14:09:23 (1778350163) [ 3291.006131] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 14:09:30 (1778350170) [ 3297.594405] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 14:09:36 (1778350176) [ 3298.347897] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 3299.177772] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 14:09:38 (1778350178) [ 3299.859783] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 3300.630737] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 14:09:39 (1778350179) [ 3301.344828] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 3302.195077] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 14:09:41 (1778350181) [ 3302.878110] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 3303.614929] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 14:09:42 (1778350182) [ 3304.422077] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 3305.369729] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 14:09:44 (1778350184) [ 3306.118630] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 3306.918664] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 14:09:45 (1778350185) [ 3307.604306] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 3308.246951] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 14:09:47 (1778350187) [ 3308.832584] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 3309.508511] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 14:09:48 (1778350188) [ 3309.589988] LustreError: 18458:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3311.671152] LustreError: 18458:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 3315.679152] Lustre: lustre-OST0001-osc-ffff9e53829a2000: disconnect after 20s idle [ 3315.682350] Lustre: Skipped 3 previous similar messages [ 3317.274881] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 14:09:56 (1778350196) [ 3322.276929] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 14:10:01 (1778350201) [ 3322.419551] LustreError: 217968:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3326.479098] LustreError: 217968:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3326.493878] LustreError: 217968:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3330.559148] LustreError: 217968:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3330.582136] LustreError: 217975:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3334.647124] LustreError: 217975:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3337.313799] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 14:10:16 (1778350216) [ 3344.166855] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 14:10:23 (1778350223) [ 3347.558629] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 14:10:26 (1778350226) [ 3352.077070] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 14:10:31 (1778350231) [ 3377.072588] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 14:10:56 (1778350256) [ 3385.514140] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 14:11:04 (1778350264) [ 3394.158171] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 14:11:13 (1778350273) [ 3407.486559] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 14:11:26 (1778350286) [ 3417.765352] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 14:11:36 (1778350296) [ 3418.487046] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 3419.126745] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 14:11:38 (1778350298) [ 3432.535337] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 14:11:51 (1778350311) [ 3447.545075] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 14:12:06 (1778350326) [ 3489.288923] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 14:12:48 (1778350368) [ 3533.218920] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 14:13:32 (1778350412) [ 3536.375881] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3539.360198] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 14:13:38 (1778350418) [ 3542.693528] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 14:13:41 (1778350421) [ 3543.405063] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3544.022177] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 14:13:43 (1778350423) [ 3544.936376] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3545.848913] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 14:13:44 (1778350424) [ 3546.603341] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 3547.349740] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 14:13:46 (1778350426) [ 3547.900743] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3548.581259] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 14:13:47 (1778350427) [ 3551.830270] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 14:13:50 (1778350430) [ 3555.191535] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 14:13:54 (1778350434) [ 3558.268150] LustreError: lustre-MDT0000-mdc-ffff9e5383546800: operation ldlm_enqueue to node 192.168.204.135@tcp failed: rc = -35 [ 3562.078039] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 14:14:01 (1778350441) [ 3562.303784] LustreError: 2368:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3564.391090] LustreError: 2368:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 3569.578790] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 14:14:08 (1778350448) [ 3593.567524] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 14:14:32 (1778350472) [ 3596.289543] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 14:14:35 (1778350475) [ 3600.845231] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 14:14:39 (1778350479) [ 3606.613982] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 14:14:45 (1778350485) [ 3612.668381] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 14:14:51 (1778350491) [ 3622.353419] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 14:15:01 (1778350501) [ 3631.537896] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 14:15:10 (1778350510) [ 3635.699610] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 14:15:14 (1778350514) [ 3640.251303] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 14:15:19 (1778350519) [ 3649.750332] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 14:15:28 (1778350528) [ 3704.511461] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 14:16:23 (1778350583) [ 3838.157665] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 14:18:37 (1778350717) [ 3935.199170] Lustre: lustre-OST0001-osc-ffff9e53829a2000: disconnect after 23s idle [ 3935.201481] Lustre: Skipped 12 previous similar messages [ 3977.593640] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 14:20:56 (1778350856) [ 4346.300283] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 14:27:05 (1778351225) [ 4388.637815] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 14:27:47 (1778351267) [ 4429.421410] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 14:28:28 (1778351308) [ 4465.699735] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 14:29:04 (1778351344) [ 4544.480244] Lustre: lustre-OST0000-osc-ffff9e53829a2000: disconnect after 22s idle [ 4544.485460] Lustre: Skipped 21 previous similar messages [ 4546.763822] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 14:30:25 (1778351425) [ 4613.556516] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 14:31:32 (1778351492) [ 4735.614342] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 14:33:34 (1778351614) [ 4736.941568] LustreError: 259979:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9e53829a2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4736.957087] Lustre: Unmounted lustre-client [ 4737.804369] LustreError: 259992:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9e5383546800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4737.807329] LustreError: 259992:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 4737.817651] Lustre: Unmounted lustre-client [ 4786.214731] Lustre: Mounted lustre-client [ 4787.954536] Lustre: Mounted lustre-client [ 4789.065659] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4876.880898] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 14:35:56 (1778351756) [ 4885.634310] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 14:36:04 (1778351764) [ 4889.725350] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 14:36:08 (1778351768) [ 4941.137299] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 14:37:00 (1778351820) [ 5009.637370] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 14:38:08 (1778351888) [ 5014.902764] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 14:38:13 (1778351893) [ 5062.242466] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 14:39:01 (1778351941) [ 5076.664761] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 14:39:15 (1778351955) [ 5116.240226] Lustre: DEBUG MARKER: == sanityn test 77s: Check TBF LRU shrinker ============== 14:39:55 (1778351995) [ 5133.723371] Lustre: DEBUG MARKER: == sanityn test 77t: check TBF minrate parsing and output ========================================================== 14:40:12 (1778352012) [ 5146.358817] Lustre: DEBUG MARKER: == sanityn test 77u: check TBF minrate on NID and generic rules ========================================================== 14:40:25 (1778352025) [ 5146.591190] Lustre: lustre-OST0000-osc-ffff9e53899a0000: disconnect after 23s idle [ 5146.593068] Lustre: Skipped 15 previous similar messages [ 5157.208988] Lustre: DEBUG MARKER: == sanityn test 77v: verify minimum RPC rate under congestion ========================================================== 14:40:36 (1778352036) [ 5157.781182] Lustre: DEBUG MARKER: SKIP: sanityn test_77v need OSTs and MDTs on separate nodes [ 5158.542750] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 14:40:37 (1778352037) [ 5162.982675] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 14:40:41 (1778352041) [ 5176.563794] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 14:40:55 (1778352055) [ 5177.118584] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 5177.957208] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 14:40:56 (1778352056) [ 5178.673658] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 5179.380337] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 14:40:58 (1778352058) [ 5179.914319] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 5180.551457] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 14:40:59 (1778352059) [ 5181.158192] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 5181.936225] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 14:41:00 (1778352060) [ 5182.497676] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5183.166352] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 14:41:02 (1778352062) [ 5220.294686] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 14:41:39 (1778352099) [ 5223.469969] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 14:41:42 (1778352102) [ 5224.221790] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 5225.123707] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 14:41:43 (1778352103) [ 5233.417380] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 14:41:52 (1778352112) [ 5236.654232] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 14:41:55 (1778352115) [ 5237.367298] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 5237.968560] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 14:41:57 (1778352117) [ 5238.506979] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 5239.205086] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 14:41:58 (1778352118) [ 5239.920132] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 5240.681158] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 14:41:59 (1778352119) [ 5250.515483] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 14:42:09 (1778352129) [ 5250.671739] Lustre: DEBUG MARKER: write [ 5250.696856] LustreError: 262058:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 5252.707054] Lustre: DEBUG MARKER: kill 292838 [ 5252.710335] LustreError: 292838:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 5255.799124] LustreError: 262058:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 5258.743142] LustreError: 292838:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 5261.700406] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 14:42:20 (1778352140) [ 5263.951602] LustreError: 293443:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 5274.047147] LustreError: 293443:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [ 5277.296253] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 14:42:36 (1778352156) [ 5277.443863] LustreError: 294022:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 5279.527117] LustreError: 294022:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [ 5286.332960] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 14:42:45 (1778352165) [ 5287.006148] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 5287.873555] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 14:42:46 (1778352166) [ 5291.109820] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 14:42:50 (1778352170) [ 5294.300669] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 14:42:53 (1778352173) [ 5297.509934] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 14:42:56 (1778352176) [ 5300.748843] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 14:42:59 (1778352179) [ 5303.422915] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 14:43:02 (1778352182) [ 5306.424737] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 14:43:05 (1778352185) [ 5310.654159] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 5311.447707] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 14:43:10 (1778352190) [ 5312.245769] Lustre: *** cfs_fail_loc=415, val=0*** [ 5319.911064] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 14:43:18 (1778352198) [ 5320.612369] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 5321.470070] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 14:43:20 (1778352200) [ 5321.599204] LustreError: 261389:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5321.605491] LustreError: 261389:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 5326.695160] LustreError: 274836:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 5326.700993] LustreError: 274836:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 2 previous similar messages [ 5336.887118] LustreError: 262058:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 5336.892304] LustreError: 262058:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 2 previous similar messages [ 5345.332447] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 14:43:44 (1778352224) [ 5346.124218] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 5346.935123] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 14:43:45 (1778352225) [ 5350.509523] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 14:43:49 (1778352229) [ 5353.698213] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 14:43:52 (1778352232) [ 5359.167173] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 14:43:58 (1778352238) [ 5368.565678] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 14:44:07 (1778352247) [ 5368.789250] LustreError: 277037:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 5368.794263] LustreError: 277037:0:(osc_request.c:2989:osc_build_rpc()) Skipped 5 previous similar messages [ 5372.855161] LustreError: 277037:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 5372.860656] LustreError: 277037:0:(osc_request.c:2989:osc_build_rpc()) Skipped 1 previous similar message [ 5376.085916] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 14:44:14 (1778352254) [ 5377.110398] LustreError: 303967:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9e53899a0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5377.116259] LustreError: 303967:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5377.139794] Lustre: Unmounted lustre-client [ 5378.262395] Lustre: DEBUG MARKER: Iteration 0 [ 5378.435376] LustreError: 304147:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5378.435495] LustreError: 304148:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5378.441234] LustreError: 304147:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5378.497190] Lustre: Mounted lustre-client [ 5379.025282] LustreError: 304254:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9e53829a4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5379.028720] LustreError: 304254:0:(lov_obd.c:786:lov_cleanup()) Skipped 5 previous similar messages [ 5379.051362] Lustre: Unmounted lustre-client [ 5379.052386] Lustre: Skipped 1 previous similar message [ 5380.457288] Key type lgssc unregistered [ 5380.624402] LNet: 304497:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5380.629696] LNetError: 304497:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5380.641553] LNet: Removed LNI 192.168.204.35@tcp [ 5381.146150] Key type .llcrypt unregistered [ 5381.148549] Key type ._llcrypt unregistered [ 5381.529795] Key type ._llcrypt registered [ 5381.530878] Key type .llcrypt registered [ 5381.832331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5381.839763] alg: No test for adler32 (adler32-zlib) [ 5382.893822] Lustre: Lustre: Build Version: 2.17.52_4_g786747d [ 5383.203274] LNet: Added LNI 192.168.204.35@tcp [8/256/0/180] [ 5384.863176] Key type lgssc registered [ 5385.697762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5391.060282] Lustre: DEBUG MARKER: Iteration 1 [ 5391.193240] LustreError: 305319:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5391.193304] LustreError: 305320:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5391.197850] LustreError: 305319:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5392.253040] Lustre: Mounted lustre-client [ 5392.768738] LustreError: 305429:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9e53899a5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5392.772226] LustreError: 305429:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5392.794516] Lustre: Unmounted lustre-client [ 5393.989643] Key type lgssc unregistered [ 5394.103162] LNet: 305671:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5394.106375] LNetError: 305671:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5394.115047] LNet: Removed LNI 192.168.204.35@tcp [ 5394.480165] Key type .llcrypt unregistered [ 5394.482693] Key type ._llcrypt unregistered [ 5394.862185] Key type ._llcrypt registered [ 5394.863295] Key type .llcrypt registered [ 5395.088234] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5395.104754] alg: No test for adler32 (adler32-zlib) [ 5396.040537] Lustre: Lustre: Build Version: 2.17.52_4_g786747d [ 5396.166337] LNet: Added LNI 192.168.204.35@tcp [8/256/0/180] [ 5397.783171] Key type lgssc registered [ 5398.164623] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5402.435388] Lustre: DEBUG MARKER: Iteration 2 [ 5402.559702] LustreError: 306491:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5402.559799] LustreError: 306492:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5402.563918] LustreError: 306491:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5403.619594] Lustre: Mounted lustre-client [ 5403.622066] Lustre: Skipped 1 previous similar message [ 5404.189850] LustreError: 306599:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9e5384ca7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5404.193287] LustreError: 306599:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5404.211984] Lustre: Unmounted lustre-client [ 5405.608466] Key type lgssc unregistered [ 5405.772634] LNet: 306843:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5405.778210] LNetError: 306843:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5405.790264] LNet: Removed LNI 192.168.204.35@tcp [ 5406.179162] Key type .llcrypt unregistered [ 5406.181438] Key type ._llcrypt unregistered [ 5406.560345] Key type ._llcrypt registered [ 5406.561549] Key type .llcrypt registered [ 5406.765251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5406.770081] alg: No test for adler32 (adler32-zlib) [ 5407.690991] Lustre: Lustre: Build Version: 2.17.52_4_g786747d [ 5407.832466] LNet: Added LNI 192.168.204.35@tcp [8/256/0/180] [ 5409.431124] Key type lgssc registered [ 5409.853788] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5415.270680] Lustre: Mounted lustre-client [ 5418.620719] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 14:44:57 (1778352297) [ 5475.295231] Lustre: 308175:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778352298/real 1778352298] req@ffff9e53c1237b80 x1864737527702272/t0(0) o36->lustre-MDT0000-mdc-ffff9e5387863000@192.168.204.135@tcp:12/10 lens 496/440 e 0 to 1 dl 1778352354 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5475.317700] Lustre: lustre-MDT0000-mdc-ffff9e5387863000: Connection to lustre-MDT0000 (at 192.168.204.135@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5475.333897] Lustre: lustre-MDT0000-mdc-ffff9e5387863000: Connection restored to 192.168.204.135@tcp (at 192.168.204.135@tcp) [ 5530.591173] Lustre: 308175:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778352354/real 1778352354] req@ffff9e53c1237b80 x1864737527702272/t0(0) o36->lustre-MDT0000-mdc-ffff9e5387863000@192.168.204.135@tcp:12/10 lens 496/440 e 0 to 1 dl 1778352410 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5530.607825] Lustre: lustre-MDT0000-mdc-ffff9e5387863000: Connection to lustre-MDT0000 (at 192.168.204.135@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5530.622636] Lustre: lustre-MDT0000-mdc-ffff9e5387863000: Connection restored to 192.168.204.135@tcp (at 192.168.204.135@tcp) [ 5586.911134] Lustre: 308175:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778352410/real 1778352410] req@ffff9e53c1237b80 x1864737527702272/t0(0) o36->lustre-MDT0000-mdc-ffff9e5387863000@192.168.204.135@tcp:12/10 lens 496/440 e 0 to 1 dl 1778352466 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5586.918408] Lustre: lustre-MDT0000-mdc-ffff9e5387863000: Connection to lustre-MDT0000 (at 192.168.204.135@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5586.925635] Lustre: lustre-MDT0000-mdc-ffff9e5387863000: Connection restored to 192.168.204.135@tcp (at 192.168.204.135@tcp) [ 5587.375961] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 14:47:46 (1778352466) [ 5588.218240] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 5589.130972] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 14:47:48 (1778352468) [ 5589.767552] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 5590.509743] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 14:47:49 (1778352469) [ 5593.603443] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 14:47:52 (1778352472) [ 5594.368140] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 5595.255730] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 14:47:54 (1778352474) [ 5596.066606] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 5596.988584] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 14:47:55 (1778352475) [ 5597.698758] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 5598.643891] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 14:47:57 (1778352477) [ 5598.772308] LustreError: 310844:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 5600.855103] LustreError: 310844:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 5603.905577] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 14:48:02 (1778352482) [ 5622.687238] Lustre: 307036:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778352486/real 1778352486] req@ffff9e53c1237480 x1864737527745920/t0(0) o4->lustre-OST0000-osc-ffff9e5387863000@192.168.204.135@tcp:6/4 lens 4584/448 e 0 to 1 dl 1778352502 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5622.701085] Lustre: lustre-OST0000-osc-ffff9e5387863000: Connection to lustre-OST0000 (at 192.168.204.135@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5622.718604] Lustre: lustre-OST0000-osc-ffff9e5387863000: Connection restored to 192.168.204.135@tcp (at 192.168.204.135@tcp) [ 5639.135181] Lustre: 307038:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778352502/real 1778352502] req@ffff9e53876e8000 x1864737527746304/t0(0) o4->lustre-OST0000-osc-ffff9e5387863000@192.168.204.135@tcp:6/4 lens 4584/448 e 0 to 1 dl 1778352518 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5639.135271] Lustre: lustre-OST0000-osc-ffff9e5387863000: Connection to lustre-OST0000 (at 192.168.204.135@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5639.156058] Lustre: 307038:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 5639.168110] Lustre: lustre-OST0000-osc-ffff9e5387863000: Connection restored to 192.168.204.135@tcp (at 192.168.204.135@tcp) [ 5654.495207] Lustre: 307038:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1778352518/real 1778352518] req@ffff9e53876e8000 x1864737527746304/t0(0) o4->lustre-OST0000-osc-ffff9e5387863000@192.168.204.135@tcp:6/4 lens 4584/448 e 0 to 1 dl 1778352534 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5654.513696] Lustre: lustre-OST0000-osc-ffff9e5387863000: Connection to lustre-OST0000 (at 192.168.204.135@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5654.531926] Lustre: lustre-OST0000-osc-ffff9e5387863000: Connection restored to 192.168.204.135@tcp (at 192.168.204.135@tcp) [ 5671.636237] Lustre: DEBUG MARKER: oleg435-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e5386056000.ost_server_uuid 50 [ 5672.336812] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e5386056000.ost_server_uuid in IDLE state after 0 sec [ 5672.961942] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 5673.812067] Lustre: DEBUG MARKER: == sanityn test complete, duration 5409 sec ============== 14:49:12 (1778352552) [ 5674.680522] Lustre: DEBUG MARKER: === sanityn: start cleanup 14:49:13 (1778352553) === [ 5748.107428] LustreError: 312851:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9e5386056000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5748.128387] Lustre: Unmounted lustre-client [ 5750.062313] Lustre: DEBUG MARKER: === sanityn: finish cleanup 14:50:28 (1778352628) === [ 5750.433497] LustreError: 313152:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9e5387863000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5750.437333] LustreError: 313152:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5750.461949] Lustre: Unmounted lustre-client [ 5764.744205] Key type lgssc unregistered [ 5764.906215] LNet: 313631:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5764.911587] LNetError: 313631:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5764.923510] LNet: Removed LNI 192.168.204.35@tcp [ 5765.340170] Key type .llcrypt unregistered [ 5765.342433] Key type ._llcrypt unregistered