[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 473649287 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 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.001012] APIC: Switch to symmetric I/O mode setup [ 0.003394] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.008597] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.011008] pid_max: default: 32768 minimum: 301 [ 0.012144] LSM: Security Framework initializing [ 0.013059] Yama: becoming mindful. [ 0.014039] SELinux: Initializing. [ 0.015085] *** VALIDATE selinux *** [ 0.023606] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028536] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029153] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030117] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031114] *** VALIDATE tmpfs *** [ 0.032467] *** VALIDATE proc *** [ 0.033257] *** VALIDATE cgroup *** [ 0.034010] *** VALIDATE cgroup2 *** [ 0.036274] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037161] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039033] Spectre V2 : User space: Vulnerable [ 0.040011] Speculative Store Bypass: Vulnerable [ 0.042644] debug: unmapping init [mem 0xffffffff91e59000-0xffffffff91e60fff] [ 0.044202] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045720] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046026] ... version: 2 [ 0.047012] ... bit width: 48 [ 0.048015] ... generic registers: 4 [ 0.049014] ... value mask: 0000ffffffffffff [ 0.050019] ... max period: 00007fffffffffff [ 0.051015] ... fixed-purpose events: 3 [ 0.052026] ... event mask: 000000070000000f [ 0.053329] rcu: Hierarchical SRCU implementation. [ 0.055499] smp: Bringing up secondary CPUs ... [ 0.056622] x86: Booting SMP configuration: [ 0.057029] .... node #0, CPUs: #1 #2 #3 [ 0.060324] smp: Brought up 1 node, 4 CPUs [ 0.062014] smpboot: Max logical packages: 1 [ 0.063021] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.144101] node 0 deferred pages initialised in 80ms [ 0.147218] devtmpfs: initialized [ 0.149264] x86/mm: Memory block size: 128MB [ 0.153000] gcov: version magic: 0x41383552 [ 0.154362] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.157087] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.160330] pinctrl core: initialized pinctrl subsystem [ 0.162188] [ 0.162851] ************************************************************* [ 0.165014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.168015] ** ** [ 0.170012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.173015] ** ** [ 0.175013] ** This means that this kernel is built to expose internal ** [ 0.177011] ** IOMMU data structures, which may compromise security on ** [ 0.180014] ** your system. ** [ 0.182011] ** ** [ 0.185014] ** If you see this message and you are not debugging the ** [ 0.187012] ** kernel, report this immediately to your vendor! ** [ 0.190012] ** ** [ 0.192011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.194062] ************************************************************* [ 0.197840] NET: Registered protocol family 16 [ 0.200499] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.202059] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.205059] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.209163] cpuidle: using governor menu [ 0.210849] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.214541] PCI: Using configuration type 1 for base access [ 0.216188] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.226049] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.229057] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.234105] cryptd: max_cpu_qlen set to 1000 [ 0.236263] ACPI: Added _OSI(Module Device) [ 0.237012] ACPI: Added _OSI(Processor Device) [ 0.239017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.241016] ACPI: Added _OSI(Processor Aggregator Device) [ 0.246000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.251628] ACPI: Interpreter enabled [ 0.253139] ACPI: PM: (supports S0 S3 S4 S5) [ 0.255016] ACPI: Using IOAPIC for interrupt routing [ 0.257111] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.261533] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.270365] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.273045] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.275019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.279079] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.284349] acpiphp: Slot [2] registered [ 0.285189] acpiphp: Slot [5] registered [ 0.287106] acpiphp: Slot [6] registered [ 0.288103] acpiphp: Slot [3] registered [ 0.290100] acpiphp: Slot [4] registered [ 0.291079] acpiphp: Slot [7] registered [ 0.292118] acpiphp: Slot [8] registered [ 0.294130] acpiphp: Slot [9] registered [ 0.295169] acpiphp: Slot [10] registered [ 0.297109] acpiphp: Slot [11] registered [ 0.299121] acpiphp: Slot [12] registered [ 0.300053] acpiphp: Slot [13] registered [ 0.302163] acpiphp: Slot [14] registered [ 0.303111] acpiphp: Slot [15] registered [ 0.305102] acpiphp: Slot [16] registered [ 0.307121] acpiphp: Slot [17] registered [ 0.309106] acpiphp: Slot [18] registered [ 0.310077] acpiphp: Slot [19] registered [ 0.311111] acpiphp: Slot [20] registered [ 0.312113] acpiphp: Slot [21] registered [ 0.314231] acpiphp: Slot [22] registered [ 0.316110] acpiphp: Slot [23] registered [ 0.317163] acpiphp: Slot [24] registered [ 0.319129] acpiphp: Slot [25] registered [ 0.321172] acpiphp: Slot [26] registered [ 0.322153] acpiphp: Slot [27] registered [ 0.324118] acpiphp: Slot [28] registered [ 0.326246] acpiphp: Slot [29] registered [ 0.327117] acpiphp: Slot [30] registered [ 0.329122] acpiphp: Slot [31] registered [ 0.331077] PCI host bridge to bus 0000:00 [ 0.332031] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.334027] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.337025] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.339025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.342027] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.345028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.347154] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.350010] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.353315] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.361015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.365084] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.368021] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.370020] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.373018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.375801] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.378762] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.382041] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.384939] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.389016] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.400016] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.404015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.410000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.426016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.441022] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.467018] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.484819] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.493017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.502021] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.521014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.532696] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.535388] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.538354] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.541349] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.542184] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.547683] iommu: Default domain type: Passthrough [ 0.549385] SCSI subsystem initialized [ 0.550142] ACPI: bus type USB registered [ 0.551096] usbcore: registered new interface driver usbfs [ 0.553048] usbcore: registered new interface driver hub [ 0.554058] usbcore: registered new device driver usb [ 0.555106] pps_core: LinuxPPS API ver. 1 registered [ 0.557014] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.560090] PTP clock support registered [ 0.561379] EDAC MC: Ver: 3.0.0 [ 0.562603] PCI: Using ACPI for IRQ routing [ 0.564798] NetLabel: Initializing [ 0.566011] NetLabel: domain hash size = 128 [ 0.568011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.569078] NetLabel: unlabeled traffic allowed by default [ 0.571277] vgaarb: loaded [ 0.573266] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.575019] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.582000] clocksource: Switched to clocksource kvm-clock [ 0.692962] VFS: Disk quotas dquot_6.6.0 [ 0.695374] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.697464] *** VALIDATE ramfs *** [ 0.698738] *** VALIDATE hugetlbfs *** [ 0.700400] pnp: PnP ACPI init [ 0.702861] pnp: PnP ACPI: found 6 devices [ 0.720222] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.722316] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.723726] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.725020] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.726472] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.728105] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.731018] NET: Registered protocol family 2 [ 0.733527] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.738995] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.742892] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.747422] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.751041] TCP: Hash tables configured (established 65536 bind 65536) [ 0.754209] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.757996] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.760662] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.763694] NET: Registered protocol family 1 [ 0.767734] RPC: Registered named UNIX socket transport module. [ 0.770283] RPC: Registered udp transport module. [ 0.772089] RPC: Registered tcp transport module. [ 0.773815] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.776300] NET: Registered protocol family 44 [ 0.777827] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.779872] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.782263] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.784666] PCI: CLS 0 bytes, default 64 [ 0.786185] Unpacking initramfs... [ 2.112430] debug: unmapping init [mem 0xffff944abcc64000-0xffff944abffcffff] [ 2.115542] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.117431] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.120124] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.569583] Initialise system trusted keyrings [ 2.570793] Key type blacklist registered [ 2.572260] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.579307] zbud: loaded [ 2.582153] *** VALIDATE nfs *** [ 2.583462] *** VALIDATE nfs4 *** [ 2.584773] pstore: using deflate compression [ 2.588097] Platform Keyring initialized [ 2.678777] NET: Registered protocol family 38 [ 2.680687] Key type asymmetric registered [ 2.682100] Asymmetric key parser 'x509' registered [ 2.683908] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.686904] io scheduler mq-deadline registered [ 2.688773] io scheduler kyber registered [ 2.690142] io scheduler bfq registered [ 2.691981] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.694886] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.696901] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.698733] ACPI: Power Button [PWRF] [ 2.703513] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.710091] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.725154] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.752784] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.780248] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.784509] Non-volatile memory driver v1.3 [ 2.785641] Linux agpgart interface v0.103 [ 2.809352] virtio_blk virtio1: [vda] 134872 512-byte logical blocks (69.1 MB/65.9 MiB) [ 2.811475] vda: detected capacity change from 0 to 69054464 [ 2.823888] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.825892] vdb: detected capacity change from 0 to 1073741824 [ 2.831111] libphy: Fixed MDIO Bus: probed [ 2.843810] usbcore: registered new interface driver usbserial_generic [ 2.848480] usbserial: USB Serial support registered for generic [ 2.852538] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.855981] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.857303] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.859267] mousedev: PS/2 mouse device common for all mice [ 2.862956] rtc_cmos 00:05: RTC can wake from S4 [ 2.866741] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.867718] rtc_cmos 00:05: registered as rtc0 [ 2.871568] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.874857] intel_pstate: CPU model not supported [ 2.875881] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.882677] hid: raw HID events driver (C) Jiri Kosina [ 2.889700] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.892157] usbcore: registered new interface driver usbhid [ 2.892163] usbhid: USB HID core driver [ 2.892335] drop_monitor: Initializing network drop monitor service [ 2.892460] Initializing XFRM netlink socket [ 2.893454] NET: Registered protocol family 10 [ 2.909127] Segment Routing with IPv6 [ 2.910620] NET: Registered protocol family 17 [ 2.912341] mpls_gso: MPLS GSO support [ 2.920460] RAS: Correctable Errors collector initialized. [ 2.922487] AVX version of gcm_enc/dec engaged. [ 2.924304] AES CTR mode by8 optimization enabled [ 2.996834] sched_clock: Marking stable (2996760344, 0)->(3904738555, -907978211) [ 3.000654] registered taskstats version 1 [ 3.002535] Loading compiled-in X.509 certificates [ 3.004185] zswap: loaded using pool lzo/zbud [ 3.022571] Key type big_key registered [ 3.032755] Key type encrypted registered [ 3.033952] ima: No TPM chip found, activating TPM-bypass! [ 3.035427] ima: Allocated hash algorithm: sha1 [ 3.036538] ima: No architecture policies found [ 3.037796] evm: Initialising EVM extended attributes: [ 3.039186] evm: security.selinux [ 3.039931] evm: security.ima [ 3.040720] evm: security.capability [ 3.041612] evm: HMAC attrs: 0x1 [ 3.043256] rtc_cmos 00:05: setting system clock to 2026-06-01 14:10:09 UTC (1780323009) [ 3.047470] debug: unmapping init [mem 0xffffffff92e03000-0xffffffff92ffffff] [ 3.049418] debug: unmapping init [mem 0xffffffff91b82000-0xffffffff91e58fff] [ 3.056063] Write protecting the kernel read-only data: 28672k [ 3.058512] debug: unmapping init [mem 0xffffffff90203000-0xffffffff903fffff] [ 3.060408] debug: unmapping init [mem 0xffffffff90b14000-0xffffffff90bfffff] [ 3.084099] 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) [ 3.090655] systemd[1]: Detected virtualization kvm. [ 3.092211] systemd[1]: Detected architecture x86-64. [ 3.093866] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.115702] systemd[1]: No hostname configured. [ 3.117093] systemd[1]: Set hostname to . [ 3.119042] random: systemd: uninitialized urandom read (16 bytes read) [ 3.121373] systemd[1]: Initializing machine ID from random generator. [ 3.156627] random: ln: uninitialized urandom read (6 bytes read) [ 3.245614] random: systemd: uninitialized urandom read (16 bytes read) [ 3.247419] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.252895] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.255785] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Reached target Initrd Root Device. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Slices. [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.740855] device-mapper: uevent: version 1.0.3 [ 3.743139] 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. [ 4.401212] virtio_net virtio0 ens2: renamed from eth0 [ 4.442457] scsi host0: ata_piix [ 4.450965] scsi host1: ata_piix [ 4.452922] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.456172] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 8.122347] dracut-initqueue[584]: RTNETLINK answers: File exists [ 9.546948] random: crng init done [ 9.548671] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 9.788455] 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 Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 10.904207] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.196402] SELinux: Disabled at runtime. [ 11.258990] 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.266706] systemd[1]: Detected virtualization kvm. [ 11.268399] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.753980] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.758725] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.763228] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.767699] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.771651] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.779727] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.788188] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Listening on Process Core Dump Socket. Mounting Kernel Debug File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on initctl Compatibility Named Pipe. Starting Remount Root and Kernel File Systems... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Huge Pages File System... Activating swap /dev/disk/by-label/SWAP... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ 11.949626] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... [ OK ] Started Journal Service. [ 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 Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.210933] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.494694] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.546456] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.636163] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.661688] EDAC sbridge: Ver: 1.1.2 [ 13.550900] Key type dns_resolver registered [ 13.865979] NFS: Registering the id_resolver key type [ 13.868370] Key type id_resolver registered [ 13.869958] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... [ OK ] Started Login Service. [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting 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. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Authorization Manager. [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg256-client login: [ 43.158268] libcfs: loading out-of-tree module taints kernel. [ 43.194115] Key type ._llcrypt registered [ 43.196660] Key type .llcrypt registered [ 43.551647] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 43.568244] alg: No test for adler32 (adler32-zlib) [ 45.424479] Lustre: Lustre: Build Version: 2.17.53_35_g98ac157 [ 46.661699] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 48.352157] Key type lgssc registered [ 49.887674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 195.389596] Lustre: Mounted lustre-client [ 200.434765] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 214.837780] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing check_logdir /tmp/testlogs/ [ 219.178346] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing yml_node [ 221.156438] Lustre: lustre-OST0000-osc-ffff944b068fb800: disconnect after 23s idle [ 223.619512] Lustre: DEBUG MARKER: Client: 2.17.53.35 [ 226.253069] Lustre: DEBUG MARKER: MDS: 2.17.53.35 [ 228.817468] Lustre: DEBUG MARKER: OSS: 2.17.53.35 [ 230.277404] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Mon Jun 1 10:13:55 EDT 2026 [ 233.616198] hrtimer: interrupt took 4177401 ns [ 249.300242] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 250.590959] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 251.982971] Lustre: DEBUG MARKER: === sanityn: start setup 10:14:17 (1780323257) === [ 252.811604] Lustre: Mounted lustre-client [ 256.307614] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing check_config_client /mnt/lustre [ 272.526274] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 283.026209] Lustre: DEBUG MARKER: === sanityn: finish setup 10:14:48 (1780323288) === [ 285.311423] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 10:14:50 (1780323290) [ 293.494385] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 10:14:58 (1780323298) [ 300.039033] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 10:15:05 (1780323305) [ 305.371600] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 10:15:10 (1780323310) [ 310.466261] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 10:15:15 (1780323315) [ 315.644646] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 10:15:21 (1780323321) [ 320.868907] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 10:15:26 (1780323326) [ 326.391571] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 10:15:31 (1780323331) [ 327.745794] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 328.983385] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 10:15:34 (1780323334) [ 334.648798] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 10:15:40 (1780323340) [ 339.555832] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 10:15:45 (1780323345) [ 345.360529] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 10:15:50 (1780323350) [ 350.180146] Lustre: lustre-OST0000-osc-ffff944b068fb800: disconnect after 21s idle [ 350.187289] Lustre: Skipped 1 previous similar message [ 350.795045] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 10:15:56 (1780323356) [ 355.884771] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 10:16:01 (1780323361) [ 360.287542] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 10:16:05 (1780323365) [ 365.324344] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 10:16:10 (1780323370) [ 365.536591] Lustre: lustre-OST0001-osc-ffff944b06389800: disconnect after 24s idle [ 371.060674] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 10:16:16 (1780323376) [ 375.897919] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 10:16:21 (1780323381) [ 382.189182] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 10:16:27 (1780323387) [ 386.645228] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 10:16:32 (1780323392) [ 392.099858] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 10:16:37 (1780323397) [ 392.540411] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 532.469785] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 10:18:58 (1780323538) [ 535.449594] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 10:19:01 (1780323541) [ 537.891036] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 10:19:03 (1780323543) [ 540.284910] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 10:19:06 (1780323546) [ 542.877030] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 10:19:08 (1780323548) [ 545.631690] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 10:19:11 (1780323551) [ 546.324389] Lustre: DEBUG MARKER: chmod [ 548.913227] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 10:19:14 (1780323554) [ 571.867845] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7527424KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 581.682677] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 10:19:47 (1780323587) [ 602.896902] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 10:20:08 (1780323608) [ 610.229369] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 10:20:16 (1780323616) [ 611.025170] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 611.575981] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 10:20:17 (1780323617) [ 627.899651] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 10:20:33 (1780323633) [ 630.790981] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 10:20:36 (1780323636) [ 631.274868] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.320747] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.355257] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.378618] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.400894] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.427185] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.453327] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.480434] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.510352] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.537877] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.566748] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.590483] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.617817] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.645236] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.679111] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.711793] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.735470] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.758996] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.781504] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.805672] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.829345] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.854341] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.879581] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.904335] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.927688] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.950328] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.974557] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 631.999875] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.026258] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.052240] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.070961] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.090796] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.115248] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.140542] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.165940] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.193039] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.219206] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.241879] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.265353] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.290129] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.318722] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.343947] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.369440] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.392682] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.420197] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.445605] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.470975] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.500501] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.523992] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.546576] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.568419] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.589706] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.613713] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.636049] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.663247] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.693610] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.731608] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.757365] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.783447] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.831267] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.862077] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.888774] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.919090] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.952314] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 632.982527] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.008566] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.043488] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.078170] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.115676] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.153182] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.180861] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.214518] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.243469] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.272346] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.301687] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.331134] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.358167] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.383940] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.413056] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.455086] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.501942] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.533352] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.560248] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.596076] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.626951] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.667910] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.704211] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.759594] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.790931] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.837298] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.872580] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.911379] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.948458] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 633.976494] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.002674] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.032349] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.066061] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.100521] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.125557] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.153510] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.187843] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.233272] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.272053] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.313469] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.371068] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.416700] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.451416] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.482249] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.512768] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.536860] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.572416] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.602917] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.638405] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.672623] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.720116] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.754898] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.786515] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.816037] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.840799] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.883056] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.924184] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 634.972752] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.002411] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.031262] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.069222] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.105961] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.139491] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.179772] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.214079] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.257820] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.300417] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.341137] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.385481] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.420758] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.459131] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.489240] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.519914] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.548086] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.580416] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.609932] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.639727] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.667616] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.695378] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.727138] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.755591] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.787400] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.820947] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.848728] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.877721] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.906693] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.936704] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 635.970951] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.001956] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.034837] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.064436] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.093127] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.117547] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.158540] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.199081] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.222911] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.246020] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.271108] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.295094] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.318382] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.340595] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.363639] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.394928] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.432630] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.464301] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.499921] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.529450] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.569331] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.606541] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.631190] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.663375] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.691040] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.720154] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.744034] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.772903] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.801370] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.826602] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.849658] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.875676] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.902987] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.928247] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.957054] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 636.982772] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.008639] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.038201] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.062804] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.089913] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.113385] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.135277] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.169556] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.199403] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.225801] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.256363] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.283596] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.311497] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.339370] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.369875] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.393994] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.418247] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.444303] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.469959] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.494247] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.527503] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.556444] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.583632] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.607769] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.632883] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.660124] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.683974] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.709360] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.735026] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.758171] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.779958] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.802834] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.829959] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.862177] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.883780] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.906141] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.936824] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 637.978891] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.008914] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.034443] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.059170] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.085984] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.112450] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.138936] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.162205] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.188446] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.219546] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.248375] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.273390] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.295950] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.317612] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.342153] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.366870] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.390871] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.412944] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.436161] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.460809] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.485075] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.512767] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.574043] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.598743] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.623557] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.647651] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.674088] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.697733] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.723980] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.752401] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.784414] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.816250] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.847714] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.878325] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.915499] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.956210] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 638.983757] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.015858] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.046616] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.074770] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.103447] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.132926] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.159144] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.182289] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.208773] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.231150] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.254309] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.277168] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.301983] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.327515] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.358700] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.389982] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.426327] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.455518] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.486313] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.517784] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.548499] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.586133] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.615526] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.646026] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.685502] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.731293] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.778158] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.815219] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.843462] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.873803] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.912765] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.951244] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 639.984723] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.023737] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.048107] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.075806] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.106097] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.140900] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.172344] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.205481] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.230026] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.257513] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.288756] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.316246] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.343077] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.369558] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.404277] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.428166] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.453091] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.477874] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.501299] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.524603] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.550434] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.575052] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.600033] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.628433] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.654940] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.679465] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.700309] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.721676] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.742755] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.764051] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.787259] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.814323] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.847928] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.872350] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.895345] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.918135] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.948621] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.973658] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 640.998619] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.018719] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.041090] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.062418] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.085089] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.115802] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.149222] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.179308] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.207820] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.230694] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.253601] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.286480] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.314499] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.347173] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.387070] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.427375] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.453598] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.481372] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.511458] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.541803] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.574696] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.611831] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.638826] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.661783] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.691505] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.719904] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.746746] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.776258] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.805709] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.828021] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.854614] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.876038] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.897024] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.920331] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.944547] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.973051] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 641.996987] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.021368] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.044683] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.075649] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.100793] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.124164] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.146887] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.166405] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.188314] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.209582] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.233310] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.257428] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.281995] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.306355] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.330676] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.361066] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.385264] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.412750] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.435338] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.460022] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.480786] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.503194] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.529775] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.552932] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.576475] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.595710] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.615669] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.637632] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.663278] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.689119] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.713173] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.743397] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.766083] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.788023] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.809049] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.828922] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.851445] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.875777] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.900474] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.922125] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.943167] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.964215] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 642.992038] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.023474] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.044906] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.066832] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.089672] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.111583] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.131528] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.153832] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.179252] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.204145] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.227402] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.254077] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.276358] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.296359] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.316822] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.340288] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.363143] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.384581] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.407364] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.429327] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.452458] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.477477] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.504306] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.529364] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.552200] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.575741] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.601107] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.625156] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.648393] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.672933] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.697059] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.718254] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.741278] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.761482] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.784329] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.813484] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.839823] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.872573] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.899069] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.928071] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.955505] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 643.989252] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.036449] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.069948] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.110026] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.138301] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.161985] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.187168] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.219492] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.242890] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.264496] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.288019] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.312067] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.335368] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.357403] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.380515] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.409602] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.434866] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.461266] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.490587] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.513621] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.534173] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.553163] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.575164] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.599941] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.627810] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.657129] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.678075] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.699710] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.723361] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.748749] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.773078] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.797532] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.821992] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.845540] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.870082] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.895176] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.915899] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.941343] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.967357] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 644.996095] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.020028] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.039815] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.063594] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.089579] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.115106] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.144885] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.167812] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.192127] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.218641] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.247965] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.271787] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.298367] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.320018] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.344032] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.370267] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.390741] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.411151] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.437369] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.464145] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.488178] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.513517] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.542142] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.566366] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.586353] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.613028] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.640643] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.662577] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.683135] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.708719] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.735200] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.758993] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.781396] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.803283] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.828583] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.853603] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.878161] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.900669] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.923176] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.948067] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.973138] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 645.996347] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.024396] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.053293] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.076682] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.101099] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.127209] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.148156] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.171309] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.192733] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.213586] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.237637] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.261759] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.282932] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.304380] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.329457] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.351512] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.373338] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.395102] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.419233] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.446026] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.469643] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.490934] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.515314] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.537223] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.559245] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.579971] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.603379] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.629131] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.652460] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.675372] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.698399] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.722571] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.747820] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.773214] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.796591] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.820701] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.851769] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.879516] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.902556] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.928214] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.958121] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 646.986576] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.017696] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.050409] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.072835] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.094641] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.115984] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.137360] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.162293] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.184536] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.209574] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.230387] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.252437] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.277970] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.301687] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.323785] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.345649] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.367864] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.393545] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.414124] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.439567] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.462768] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.488752] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.512095] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.538970] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.566134] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.589031] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.610863] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.630947] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.654971] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.679754] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.700528] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.721270] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.741663] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.763385] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.789102] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.810980] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.835534] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.861238] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.896871] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.930675] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.962024] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 647.993388] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.028824] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.059457] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.091239] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.120924] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.152282] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.183759] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.211586] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.238270] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.266376] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.298615] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.330379] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.361268] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.392019] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.423943] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.453544] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.498975] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.533543] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.571295] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.597553] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.622540] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.649587] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.673574] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.696224] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.721323] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.748577] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.776457] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.801812] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.828388] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.853776] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.878400] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.901203] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.932435] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.953764] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.974944] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 648.996370] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.018873] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.042040] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.068600] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.096442] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.122727] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.144399] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.164928] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.185784] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.208817] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.230873] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.257218] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.280090] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.300864] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.323759] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.346485] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.368790] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.401739] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.423265] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.451641] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.484596] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.521854] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.551237] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.576406] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.601632] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.625170] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.649218] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.677331] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.700191] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.726469] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.748544] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.770853] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.793792] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.814405] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.836018] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.858075] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.879527] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.900357] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.922392] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.945476] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.971194] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 649.996818] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.022558] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.047206] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.068794] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.089546] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.109128] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.132865] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.157171] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.183252] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.212404] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.233258] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.263263] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.301238] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.333446] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.360704] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.392220] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.418693] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.457894] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.492449] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.524276] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.557473] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.588136] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.621095] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.654831] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.679225] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.720550] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.760844] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.795153] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.822792] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 650.858084] rw_seq_cst_vs_d (29573): drop_caches: 3 [ 652.256139] Lustre: lustre-OST0000-osc-ffff944b068fb800: disconnect after 20s idle [ 653.745473] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 10:20:59 (1780323659) [ 653.977199] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.044119] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.068905] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.109304] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.131260] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.150694] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.225757] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.256646] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.314584] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.337279] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.392428] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.411675] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.460183] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.501349] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.549114] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.645212] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.693475] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.726507] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.842467] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.867606] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.893437] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.918203] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.941798] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 654.978322] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.047859] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.182818] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.272524] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.323897] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.367363] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.388616] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.411334] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.427088] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.445525] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.460975] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.489128] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.564923] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.587460] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.636110] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.785475] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.857093] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 655.874831] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.008381] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.119106] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.145430] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.172186] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.193510] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.221022] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.257903] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.317234] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.338130] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.360373] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.387551] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.440260] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.468037] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.527943] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.558302] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.630245] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.678321] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.701306] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.767892] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.797056] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.824415] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.866912] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.924968] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 656.968023] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.068634] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.218656] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.234822] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.283644] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.327750] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.355147] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.370617] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.387327] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.404863] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.447631] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.468764] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.486650] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.543716] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.567123] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.594032] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.649266] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.665637] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.696330] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.819423] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.852455] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.877726] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.908298] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 657.948446] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.058777] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.086486] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.150496] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.173790] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.200786] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.219803] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.237203] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.290970] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.315466] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.335375] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.364437] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.395090] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.426971] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.455937] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.512175] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.554032] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.606276] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.626864] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.823146] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.844764] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.877828] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.904308] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.964643] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 658.998198] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.043172] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.063686] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.083607] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.108424] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.228960] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.258427] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.311313] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.332479] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.363865] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.391848] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.414643] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.444624] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.470390] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.502420] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.526332] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.652575] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.700260] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.724692] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.757791] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.805641] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.835534] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.856285] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.937121] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.961947] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 659.991074] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.010170] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.062122] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.083981] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.105920] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.182826] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.232627] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.301084] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.320498] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.341504] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.365588] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.410299] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.429318] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.488640] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.711813] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.788534] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.810309] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.840031] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.867029] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.899421] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.958401] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 660.985862] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.024368] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.051981] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.077254] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.101703] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.133597] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.154868] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.188970] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.232682] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.297647] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.319420] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.352125] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.467429] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.493844] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.517404] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.580883] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.604792] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.670309] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.696152] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.725355] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.750093] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.826338] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.851696] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 661.968751] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.131202] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.163226] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.182782] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.202696] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.218334] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.252099] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.272672] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.322954] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.340213] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.363739] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.384296] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.407361] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.471735] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.490087] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.555491] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.578588] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.596182] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.675393] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.741654] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.790770] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.874055] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.894448] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.917776] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 662.939266] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.020978] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.053135] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.209645] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.246758] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.265045] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.285875] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.305750] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.332640] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.355131] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.376612] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.400382] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.421070] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.440083] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.459807] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.515510] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.535766] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.556858] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.584606] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.747928] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.865782] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.935280] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 663.971585] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.020515] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.071019] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.086702] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.113089] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.143995] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.283084] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.335470] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.359809] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.576243] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.597829] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.614149] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.644170] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.663524] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.681953] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.713252] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.730779] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.868071] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 664.952639] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.068090] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.117426] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.235153] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.256993] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.321487] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.335670] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.354623] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.373891] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.407362] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.460316] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.487819] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.505599] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.532748] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.567685] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.592448] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.624183] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.642475] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.659509] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.679968] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.783946] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.820338] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.840464] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.866365] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.910082] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.938306] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.955587] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 665.984399] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.004798] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.025341] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.097284] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.116618] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.140807] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.160352] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.183940] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.211888] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.230848] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.259793] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.298491] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.318053] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.334415] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.351343] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.385344] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.470230] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.488232] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.507424] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.550271] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.595466] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.623571] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.658842] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.743756] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.762104] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.786391] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.832908] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.857512] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 666.934615] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.160629] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.175938] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.229087] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.269220] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.308625] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.332141] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.430802] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.448347] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.469724] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.488978] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.507992] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.527494] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.568668] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.626647] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.644256] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.665359] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.681532] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.721960] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.739898] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.760783] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.779514] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.796558] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.834284] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.875528] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.894430] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.918758] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 667.982464] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.023381] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.091738] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.131472] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.150780] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.175144] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.245858] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.270470] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.310615] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.366698] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.396047] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.423565] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.450397] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.474522] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.504061] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.521077] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.569144] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.586648] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.605161] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.622468] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.647044] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.664996] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.735909] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.761551] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.777941] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.807727] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.852670] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.896256] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.940544] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.958322] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 668.981663] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.029796] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.047588] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.093549] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.122849] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.144890] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.187447] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.235978] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.253972] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.301760] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.343976] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.458345] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.502222] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.551761] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.606677] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.626903] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.652143] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.669483] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.686162] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.705200] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.720826] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.736675] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.753238] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.769544] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.785520] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.803675] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.824247] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.870532] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.910753] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 669.984785] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.010897] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.177934] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.251167] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.285300] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.305541] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.326789] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.345856] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.369937] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.414362] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.464413] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.486430] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.533386] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.593522] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.626106] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.654413] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.699525] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.798591] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.835759] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.874120] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.928370] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 670.976276] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.010022] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.055608] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.091633] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.118420] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.160557] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.180766] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.248177] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.275438] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.296400] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.343455] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.363572] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.405574] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.428209] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.468503] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.486734] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.552064] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.596155] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.627424] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.681315] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.732093] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.758624] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.856919] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.897230] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.924897] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.953363] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 671.976434] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.005830] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.029540] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.113719] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.133851] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.163027] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.231928] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.266670] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.300426] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.384608] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.458492] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.483459] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.505405] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.572236] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.597838] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.634577] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.674898] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.722278] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.736207] Lustre: lustre-OST0001-osc-ffff944b068fb800: disconnect after 21s idle [ 672.739380] Lustre: Skipped 1 previous similar message [ 672.754044] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.775815] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.809526] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.833126] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 672.923927] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.055203] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.074488] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.096930] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.131601] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.157263] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.209863] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.249140] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.296330] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.322687] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.348156] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.447139] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.473689] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.494334] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.515451] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.550790] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.571818] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.592654] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.660196] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 673.821182] rw_seq_cst_vs_d (30153): drop_caches: 3 [ 676.706285] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 10:21:22 (1780323682) [ 678.916897] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 10:21:24 (1780323684) [ 681.132343] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 10:21:27 (1780323687) [ 691.544812] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 10:21:37 (1780323697) [ 691.795775] bash (32589): drop_caches: 3 [ 695.166773] bash (32589): drop_caches: 3 [ 698.242761] bash (32589): drop_caches: 3 [ 701.294440] bash (32589): drop_caches: 3 [ 704.388548] bash (32589): drop_caches: 3 [ 707.978688] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 10:21:53 (1780323713) [ 713.019592] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 10:21:58 (1780323718) [ 725.838106] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 10:22:11 (1780323731) [ 726.966114] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 727.622677] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 10:22:13 (1780323733) [ 730.270813] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 10:22:16 (1780323736) [ 733.045151] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 10:22:18 (1780323738) [ 744.416310] Lustre: lustre-OST0000-osc-ffff944b06389800: disconnect after 23s idle [ 744.419571] Lustre: Skipped 1 previous similar message [ 800.324246] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 10:23:25 (1780323805) [ 805.277878] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 10:23:30 (1780323810) [ 810.494269] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 10:23:35 (1780323815) [ 817.118543] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 10:23:42 (1780323822) [ 818.592160] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 819.875258] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 10:23:45 (1780323825) [ 826.338336] Lustre: lustre-OST0000-osc-ffff944b068fb800: disconnect after 21s idle [ 826.348765] Lustre: Skipped 3 previous similar messages [ 827.755498] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 10:23:52 (1780323832) [ 837.199409] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 10:24:02 (1780323842) [ 845.174700] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 847.551241] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 10:24:12 (1780323852) [ 855.710348] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 10:24:21 (1780323861) [ 856.191797] Lustre: *** cfs_fail_loc=314, val=0*** [ 857.248892] Lustre: *** cfs_fail_loc=314, val=0*** [ 857.258185] Lustre: Skipped 2 previous similar messages [ 862.842407] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 10:24:28 (1780323868) [ 877.005663] Lustre: *** cfs_fail_loc=314, val=0*** [ 882.432529] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 10:24:47 (1780323887) [ 882.631780] LustreError: 42446:0:(file.c:822:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 882.666106] Lustre: lustre-OST0000-osc-ffff944b06389800: Connection to lustre-OST0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 882.683400] LustreError: lustre-OST0000-osc-ffff944b06389800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 882.689851] Lustre: lustre-OST0000-osc-ffff944b06389800: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 885.664189] LustreError: 42446:0:(file.c:822:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 890.349370] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 10:24:55 (1780323895) [ 896.048692] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 10:25:01 (1780323901) [ 901.826254] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 903.022965] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 10:25:08 (1780323908) [ 904.214637] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 905.211471] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 10:25:10 (1780323910) [ 906.191306] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 907.298908] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 10:25:12 (1780323912) [ 908.183541] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 909.614566] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 10:25:15 (1780323915) [ 910.759526] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 911.752930] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 10:25:17 (1780323917) [ 968.647967] Lustre: lustre-OST0000-osc-ffff944b06389800: Connection to lustre-OST0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 968.677695] LustreError: lustre-OST0000-osc-ffff944b06389800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 968.690700] Lustre: lustre-OST0000-osc-ffff944b06389800: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 968.690929] LustreError: lustre-OST0000-osc-ffff944b068fb800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 985.061236] Lustre: lustre-OST0001-osc-ffff944b068fb800: Connection to lustre-OST0001 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 985.069291] Lustre: Skipped 1 previous similar message [ 985.077234] LustreError: lustre-OST0001-osc-ffff944b068fb800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 985.087195] Lustre: lustre-OST0001-osc-ffff944b068fb800: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 985.093446] Lustre: Skipped 1 previous similar message [ 990.177345] Lustre: lustre-OST0000-osc-ffff944b068fb800: disconnect after 21s idle [ 990.186183] Lustre: Skipped 1 previous similar message [ 997.141137] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff944b06389800.ost_server_uuid 50 [ 998.298487] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff944b06389800.ost_server_uuid in IDLE state after 0 sec [ 1001.211507] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff944b06389800.ost_server_uuid 50 [ 1002.292426] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff944b06389800.ost_server_uuid in FULL state after 0 sec [ 1006.367159] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff944b06389800.ost_server_uuid 50 [ 1007.751619] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff944b06389800.ost_server_uuid in IDLE state after 0 sec [ 1010.440904] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff944b06389800.ost_server_uuid 50 [ 1011.656339] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff944b06389800.ost_server_uuid in FULL state after 0 sec [ 1018.910636] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff944b06389800.ost_server_uuid 50 [ 1020.070836] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff944b06389800.ost_server_uuid in IDLE state after 0 sec [ 1022.614536] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff944b06389800.ost_server_uuid 50 [ 1023.978866] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff944b06389800.ost_server_uuid in FULL state after 0 sec [ 1025.652846] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 10:27:10 (1780324030) [ 1028.156316] Lustre: DEBUG MARKER: Race attempt 0 [ 1030.972989] Lustre: DEBUG MARKER: Wait for 49074 49107 for 60 sec... [ 1096.061393] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 10:28:21 (1780324101) [ 1103.813670] Lustre: DEBUG MARKER: start test - cycle (0) [ 1133.175563] Lustre: DEBUG MARKER: start test - cycle (1) [ 1133.536179] Lustre: lustre-OST0000-osc-ffff944b06389800: disconnect after 20s idle [ 1133.539589] Lustre: Skipped 2 previous similar messages [ 1159.631352] Lustre: DEBUG MARKER: start test - cycle (2) [ 1186.137821] Lustre: DEBUG MARKER: start test - cycle (3) [ 1211.279255] Lustre: DEBUG MARKER: start test - cycle (4) [ 1236.766471] Lustre: DEBUG MARKER: start test - cycle (5) [ 1261.882792] Lustre: DEBUG MARKER: start test - cycle (6) [ 1266.656507] Lustre: lustre-OST0000-osc-ffff944b06389800: disconnect after 23s idle [ 1266.665374] Lustre: Skipped 4 previous similar messages [ 1287.380483] Lustre: DEBUG MARKER: start test - cycle (7) [ 1313.497985] Lustre: DEBUG MARKER: start test - cycle (8) [ 1339.324938] Lustre: DEBUG MARKER: start test - cycle (9) [ 1365.003898] Lustre: DEBUG MARKER: start test - cycle (10) [ 1396.106302] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 10:33:20 (1780324400) [ 1477.594137] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 10:34:43 (1780324483) [ 1482.906935] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 10:34:48 (1780324488) [ 1488.997835] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 10:34:54 (1780324494) [ 1494.493536] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 10:35:00 (1780324500) [ 1494.740490] Lustre: *** cfs_fail_loc=411, val=0*** [ 1498.937343] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1499.976824] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 10:35:05 (1780324505) [ 1512.965111] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 10:35:18 (1780324518) [ 1525.507523] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 10:35:30 (1780324530) [ 1532.908984] Lustre: lustre-OST0001-osc-ffff944b068fb800: disconnect after 24s idle [ 1532.911795] Lustre: Skipped 11 previous similar messages [ 1538.486759] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 10:35:44 (1780324544) [ 1550.386481] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 10:35:56 (1780324556) [ 1559.144968] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 10:36:04 (1780324564) [ 1568.871132] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 10:36:14 (1780324574) [ 1577.723646] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 10:36:23 (1780324583) [ 1585.998360] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 10:36:31 (1780324591) [ 1595.144851] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 10:36:40 (1780324600) [ 1603.515181] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 10:36:49 (1780324609) [ 1612.473439] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 10:36:58 (1780324618) [ 1620.889190] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 10:37:06 (1780324626) [ 2244.579638] Lustre: lustre-OST0000-osc-ffff944b06389800: disconnect after 25s idle [ 2244.582602] Lustre: Skipped 4 previous similar messages [ 2423.020942] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 10:50:28 (1780325428) [ 2429.968814] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 10:50:35 (1780325435) [ 2437.386420] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 10:50:43 (1780325443) [ 2445.093213] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 10:50:50 (1780325450) [ 2452.409089] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 10:50:58 (1780325458) [ 2459.562168] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 10:51:05 (1780325465) [ 2467.139727] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 10:51:12 (1780325472) [ 2474.213787] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 10:51:20 (1780325480) [ 2481.212569] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 10:51:27 (1780325487) [ 2513.997330] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 10:51:59 (1780325519) [ 2521.015687] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 10:52:06 (1780325526) [ 2527.408134] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 10:52:13 (1780325533) [ 2534.241731] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 10:52:19 (1780325539) [ 2540.968319] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 10:52:26 (1780325546) [ 2547.842995] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 10:52:33 (1780325553) [ 2554.819450] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 10:52:40 (1780325560) [ 2561.891154] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 10:52:47 (1780325567) [ 2562.563519] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 2563.367984] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 10:52:49 (1780325569) [ 2616.567799] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 10:53:42 (1780325622) [ 3207.491343] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 11:03:33 (1780326213) [ 3215.557460] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 11:03:41 (1780326221) [ 3217.376593] Lustre: lustre-OST0000-osc-ffff944b06389800: disconnect after 21s idle [ 3217.385623] Lustre: Skipped 7 previous similar messages [ 3223.819379] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 11:03:49 (1780326229) [ 3231.468410] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 11:03:57 (1780326237) [ 3239.195791] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 11:04:04 (1780326244) [ 3246.982333] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 11:04:12 (1780326252) [ 3253.786584] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 11:04:19 (1780326259) [ 3260.774143] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 11:04:26 (1780326266) [ 3267.799755] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 11:04:33 (1780326273) [ 3268.419649] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 3269.277498] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 11:04:35 (1780326275) [ 3342.538404] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 11:05:48 (1780326348) [ 3350.651352] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 11:05:56 (1780326356) [ 3358.193504] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 11:06:03 (1780326363) [ 3364.951063] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 11:06:10 (1780326370) [ 3372.028864] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 11:06:17 (1780326377) [ 3379.208528] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 11:06:24 (1780326384) [ 3386.351569] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 11:06:32 (1780326392) [ 3393.198206] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 11:06:38 (1780326398) [ 3393.871150] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 3394.667865] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 11:06:40 (1780326400) [ 4142.738279] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 11:19:08 (1780327148) [ 4153.689537] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 11:19:18 (1780327158) [ 4159.457566] Lustre: lustre-OST0001-osc-ffff944b06389800: disconnect after 22s idle [ 4159.465979] Lustre: Skipped 5 previous similar messages [ 4165.960449] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 11:19:31 (1780327171) [ 4177.803656] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 11:19:43 (1780327183) [ 4188.423808] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 11:19:53 (1780327193) [ 4200.664277] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 11:20:05 (1780327205) [ 4212.913840] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 11:20:18 (1780327218) [ 4225.407247] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 11:20:30 (1780327230) [ 4239.556921] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 11:20:44 (1780327244) [ 4241.062584] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 4242.803657] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 11:20:47 (1780327247) [ 4244.491958] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 4245.907888] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 11:20:51 (1780327251) [ 4247.036972] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 4248.368260] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 11:20:53 (1780327253) [ 4249.593903] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 4251.281863] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 11:20:56 (1780327256) [ 4252.421043] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 4254.074670] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 11:20:59 (1780327259) [ 4255.206449] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 4256.747264] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 11:21:02 (1780327262) [ 4258.152971] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 4260.276412] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 11:21:05 (1780327265) [ 4261.811641] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 4263.596882] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 11:21:08 (1780327268) [ 4263.957335] LustreError: 5572:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4266.040437] LustreError: 5572:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4275.576829] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 11:21:20 (1780327280) [ 4284.790459] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 11:21:29 (1780327289) [ 4285.096900] LustreError: 217691:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4289.173847] LustreError: 217691:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4289.220904] LustreError: 217691:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4293.296139] LustreError: 217691:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4293.345582] LustreError: 217697:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4297.408133] LustreError: 217697:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4302.832805] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 11:21:48 (1780327308) [ 4313.527550] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 11:21:58 (1780327318) [ 4319.888689] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 11:22:05 (1780327325) [ 4327.756922] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 11:22:13 (1780327333) [ 4358.006416] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 11:22:43 (1780327363) [ 4369.931230] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 11:22:55 (1780327375) [ 4381.916653] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 11:23:07 (1780327387) [ 4399.812489] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 11:23:25 (1780327405) [ 4412.873715] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 11:23:38 (1780327418) [ 4414.139588] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 4415.982385] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 11:23:41 (1780327421) [ 4432.949507] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 11:23:58 (1780327438) [ 4452.878474] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 11:24:18 (1780327458) [ 4536.833812] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 11:25:42 (1780327542) [ 4615.724152] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 11:27:01 (1780327621) [ 4620.824896] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4625.309746] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 11:27:11 (1780327631) [ 4631.397282] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 11:27:16 (1780327636) [ 4632.421604] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4633.471878] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 11:27:19 (1780327639) [ 4634.510948] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4635.590848] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 11:27:21 (1780327641) [ 4636.599139] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 4637.852222] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 11:27:23 (1780327643) [ 4638.968096] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4640.108929] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 11:27:25 (1780327645) [ 4644.457177] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 11:27:30 (1780327650) [ 4648.489442] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 11:27:34 (1780327654) [ 4651.762054] LustreError: lustre-MDT0000-mdc-ffff944b068fb800: operation ldlm_enqueue to node 192.168.202.156@tcp failed: rc = -35 [ 4656.407269] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 11:27:42 (1780327662) [ 4656.761823] LustreError: 2368:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4658.848224] LustreError: 2368:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 4665.301671] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 11:27:50 (1780327670) [ 4707.796139] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 11:28:33 (1780327713) [ 4712.456512] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 11:28:38 (1780327718) [ 4719.325738] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 11:28:45 (1780327725) [ 4727.705070] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 11:28:53 (1780327733) [ 4736.354257] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 11:29:01 (1780327741) [ 4750.069948] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 11:29:15 (1780327755) [ 4763.112548] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 11:29:28 (1780327768) [ 4768.815971] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 11:29:34 (1780327774) [ 4775.353856] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 11:29:40 (1780327780) [ 4778.976192] Lustre: lustre-OST0000-osc-ffff944b068fb800: disconnect after 20s idle [ 4778.980505] Lustre: Skipped 9 previous similar messages [ 4788.679650] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 11:29:54 (1780327794) [ 4855.979908] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 11:31:00 (1780327860) [ 5015.315459] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 11:33:40 (1780328020) [ 5172.381520] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 11:36:16 (1780328176) [ 5408.737891] Lustre: lustre-OST0000-osc-ffff944b068fb800: disconnect after 20s idle [ 5408.747839] Lustre: Skipped 19 previous similar messages [ 5646.123679] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 11:44:11 (1780328651) [ 5693.621354] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 11:44:58 (1780328698) [ 5746.496754] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 11:45:50 (1780328750) [ 5797.880627] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 11:46:42 (1780328802) [ 5894.035695] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 11:48:19 (1780328899) [ 5965.563247] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 11:49:31 (1780328971) [ 6038.501084] Lustre: lustre-OST0001-osc-ffff944b068fb800: disconnect after 23s idle [ 6038.507564] Lustre: Skipped 20 previous similar messages [ 6095.476234] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 11:51:40 (1780329100) [ 6097.786031] LustreError: 257645:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff944b068fb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6097.807098] Lustre: Unmounted lustre-client [ 6099.659880] LustreError: 257658:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff944b06389800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6099.665137] LustreError: 257658:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6099.694747] Lustre: Unmounted lustre-client [ 6143.810824] Lustre: Mounted lustre-client [ 6145.808448] Lustre: Mounted lustre-client [ 6147.654524] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6236.854843] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 11:54:02 (1780329242) [ 6248.103336] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 11:54:13 (1780329253) [ 6254.516931] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 11:54:20 (1780329260) [ 6309.168996] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 11:55:14 (1780329314) [ 6380.822541] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 11:56:26 (1780329386) [ 6386.360290] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 11:56:32 (1780329392) [ 6443.261640] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 11:57:28 (1780329448) [ 6462.231699] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 11:57:47 (1780329467) [ 6506.926754] Lustre: DEBUG MARKER: == sanityn test 77s: Check TBF LRU shrinker ============== 11:58:32 (1780329512) [ 6540.162461] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 11:59:05 (1780329545) [ 6545.082952] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 11:59:10 (1780329550) [ 6559.568223] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 11:59:25 (1780329565) [ 6560.228332] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 6560.987295] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 11:59:26 (1780329566) [ 6561.725272] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 6562.553703] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 11:59:28 (1780329568) [ 6563.249268] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 6564.107288] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 11:59:29 (1780329569) [ 6564.726921] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 6565.466483] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 11:59:31 (1780329571) [ 6566.178647] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6566.964530] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 11:59:32 (1780329572) [ 6622.346937] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 12:00:28 (1780329628) [ 6625.210616] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 12:00:31 (1780329631) [ 6625.831801] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 6626.577833] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 12:00:32 (1780329632) [ 6635.107579] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 12:00:40 (1780329640) [ 6639.802740] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 12:00:45 (1780329645) [ 6640.476668] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 6641.288427] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 12:00:47 (1780329647) [ 6642.035108] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 6642.656205] Lustre: lustre-OST0001-osc-ffff944b03575000: disconnect after 20s idle [ 6642.659165] Lustre: Skipped 14 previous similar messages [ 6642.867392] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 12:00:48 (1780329648) [ 6643.616093] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 6644.394452] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 12:00:50 (1780329650) [ 6654.473673] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 12:01:00 (1780329660) [ 6654.618639] Lustre: DEBUG MARKER: write [ 6654.652988] LustreError: 259055:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6656.657557] Lustre: DEBUG MARKER: kill 287250 [ 6656.660820] LustreError: 287250:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6659.752092] LustreError: 259055:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 6662.704087] LustreError: 287250:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 6668.157544] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 12:01:13 (1780329673) [ 6670.739497] LustreError: 287866:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 6680.794198] LustreError: 287866:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [ 6691.867459] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 12:01:35 (1780329695) [ 6692.700301] LustreError: 288447:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6694.688290] LustreError: 288447:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [ 6716.393087] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 12:01:59 (1780329719) [ 6720.669325] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6726.606780] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 12:02:08 (1780329728) [ 6743.738285] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 12:02:28 (1780329748) [ 6753.374270] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 12:02:38 (1780329758) [ 6763.814940] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 12:02:47 (1780329767) [ 6778.066741] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 12:03:01 (1780329781) [ 6789.748449] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 12:03:13 (1780329793) [ 6799.977727] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 12:03:25 (1780329805) [ 6809.121627] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 6811.241068] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 12:03:36 (1780329816) [ 6812.798168] Lustre: *** cfs_fail_loc=415, val=0*** [ 6827.417576] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 12:03:52 (1780329832) [ 6829.053580] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 6830.818984] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 12:03:55 (1780329835) [ 6831.132945] LustreError: 263750:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6836.136950] LustreError: 263750:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 6846.280133] LustreError: 259054:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 6846.283785] LustreError: 259054:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 5 previous similar messages [ 6859.704436] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 12:04:24 (1780329864) [ 6861.279755] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 6862.957107] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 12:04:28 (1780329868) [ 6870.710848] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 12:04:35 (1780329875) [ 6877.583413] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 12:04:42 (1780329882) [ 6887.067272] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 12:04:52 (1780329892) [ 6900.580505] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 12:05:05 (1780329905) [ 6901.331170] LustreError: 276515:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6901.341281] LustreError: 276515:0:(osc_request.c:2989:osc_build_rpc()) Skipped 8 previous similar messages [ 6905.400164] LustreError: 276515:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6905.403980] LustreError: 276515:0:(osc_request.c:2989:osc_build_rpc()) Skipped 2 previous similar messages [ 6912.614829] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 12:05:17 (1780329917) [ 6915.127743] LustreError: 298410:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff944b03575000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6915.136365] LustreError: 298410:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6915.180378] Lustre: Unmounted lustre-client [ 6916.939200] LustreError: 298430:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff944b15771000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6916.949554] LustreError: 298430:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6917.006273] Lustre: Unmounted lustre-client [ 6918.763709] Lustre: DEBUG MARKER: Iteration 0 [ 6919.136950] LustreError: 298590:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6919.139123] LustreError: 298591:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6919.154645] LustreError: 298590:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [ 6919.456557] Lustre: Mounted lustre-client [ 6921.415925] LustreError: 298687:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff944b0809d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6921.429124] LustreError: 298687:0:(lov_obd.c:792:lov_cleanup()) Skipped 3 previous similar messages [ 6921.506402] Lustre: Unmounted lustre-client [ 6924.408371] Key type lgssc unregistered [ 6924.676769] LNet: 298931:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6924.685933] LNetError: 298931:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6924.706352] LNet: Removed LNI 192.168.202.56@tcp [ 6925.616114] Key type .llcrypt unregistered [ 6925.618407] Key type ._llcrypt unregistered [ 6926.815873] Key type ._llcrypt registered [ 6926.823925] Key type .llcrypt registered [ 6927.235653] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6927.246844] alg: No test for adler32 (adler32-zlib) [ 6928.698995] Lustre: Lustre: Build Version: 2.17.53_35_g98ac157 [ 6929.460913] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 6931.168247] Key type lgssc registered [ 6933.040948] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6946.707779] Lustre: DEBUG MARKER: Iteration 1 [ 6947.026053] LustreError: 299752:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6947.027386] LustreError: 299754:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6947.049212] LustreError: 299752:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 6948.302529] Lustre: Mounted lustre-client [ 6949.194206] LustreError: 299846:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff944b0809b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6949.265886] Lustre: Unmounted lustre-client [ 6952.110950] Key type lgssc unregistered [ 6952.404452] LNet: 300096:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6952.410577] LNetError: 300096:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6952.444106] LNet: Removed LNI 192.168.202.56@tcp [ 6953.161136] Key type .llcrypt unregistered [ 6953.165315] Key type ._llcrypt unregistered [ 6954.283428] Key type ._llcrypt registered [ 6954.286134] Key type .llcrypt registered [ 6954.801597] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6954.815426] alg: No test for adler32 (adler32-zlib) [ 6955.829406] Lustre: Lustre: Build Version: 2.17.53_35_g98ac157 [ 6956.040176] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 6957.696703] Key type lgssc registered [ 6959.087946] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6972.872275] Lustre: Mounted lustre-client [ 6973.574596] Lustre: Mounted lustre-client [ 6981.502298] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 12:06:26 (1780329986) [ 7037.923730] Lustre: 301424:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329989/real 1780329989] req@ffff944b04a15500 x1866811270244480/t0(0) o36->lustre-MDT0000-mdc-ffff944b08002000@192.168.202.156@tcp:12/10 lens 496/440 e 0 to 1 dl 1780330044 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7037.953778] Lustre: lustre-MDT0000-mdc-ffff944b08002000: Connection to lustre-MDT0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7038.001800] Lustre: lustre-MDT0000-mdc-ffff944b08002000: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 7093.216576] Lustre: 301424:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780330044/real 1780330044] req@ffff944b04a15500 x1866811270244480/t0(0) o36->lustre-MDT0000-mdc-ffff944b08002000@192.168.202.156@tcp:12/10 lens 496/440 e 0 to 1 dl 1780330099 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7093.279198] Lustre: lustre-MDT0000-mdc-ffff944b08002000: Connection to lustre-MDT0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7093.384975] Lustre: lustre-MDT0000-mdc-ffff944b08002000: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 7148.512824] Lustre: 301424:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780330099/real 1780330099] req@ffff944b04a15500 x1866811270244480/t0(0) o36->lustre-MDT0000-mdc-ffff944b08002000@192.168.202.156@tcp:12/10 lens 496/440 e 0 to 1 dl 1780330154 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7148.559800] Lustre: lustre-MDT0000-mdc-ffff944b08002000: Connection to lustre-MDT0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7148.644735] Lustre: lustre-MDT0000-mdc-ffff944b08002000: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 7150.279178] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 12:09:15 (1780330155) [ 7153.059920] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 7155.711781] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 12:09:19 (1780330159) [ 7158.068798] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 7160.063313] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 12:09:25 (1780330165) [ 7168.150314] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 12:09:32 (1780330172) [ 7169.885511] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 7172.081306] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 12:09:36 (1780330176) [ 7173.803444] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 7175.922619] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 12:09:40 (1780330180) [ 7177.723481] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 7179.769055] Lustre: DEBUG MARKER: == sanityn test 117a: TCU: Init and enable Trash Can on MDTs ========================================================== 12:09:44 (1780330184) [ 7181.570685] Lustre: DEBUG MARKER: SKIP: sanityn test_117a ldiskfs only test [ 7183.441604] Lustre: DEBUG MARKER: == sanityn test 117b: Move regular file and empty dir into trash can dir ========================================================== 12:09:48 (1780330188) [ 7185.292197] Lustre: DEBUG MARKER: SKIP: sanityn test_117b ldiskfs only test [ 7187.490990] Lustre: DEBUG MARKER: == sanityn test 117c: Move deleted tree with multiple levels into trash ========================================================== 12:09:52 (1780330192) [ 7189.464421] Lustre: DEBUG MARKER: SKIP: sanityn test_117c ldiskfs only test [ 7191.696905] Lustre: DEBUG MARKER: == sanityn test 117d: Per-User Trash can Type testing ==== 12:09:56 (1780330196) [ 7193.848111] Lustre: DEBUG MARKER: SKIP: sanityn test_117d ldiskfs only test [ 7195.947268] Lustre: DEBUG MARKER: == sanityn test 117e: Undeleted dir in trash should keep its original xattrs ========================================================== 12:10:00 (1780330200) [ 7197.526872] Lustre: DEBUG MARKER: SKIP: sanityn test_117e ldiskfs only test [ 7199.715866] Lustre: DEBUG MARKER: == sanityn test 117f: Uncache the dentry under the trash dir ========================================================== 12:10:04 (1780330204) [ 7201.241385] Lustre: DEBUG MARKER: SKIP: sanityn test_117f ldiskfs only test [ 7203.027314] Lustre: DEBUG MARKER: == sanityn test 117g: Access .Trash for a non-striped directory ========================================================== 12:10:07 (1780330207) [ 7204.539236] Lustre: DEBUG MARKER: SKIP: sanityn test_117g ldiskfs only test [ 7206.225892] Lustre: DEBUG MARKER: == sanityn test 117h: Permission testing of various operations for files in Trash Can ========================================================== 12:10:11 (1780330211) [ 7207.603369] Lustre: DEBUG MARKER: SKIP: sanityn test_117h ldiskfs only test [ 7209.411383] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 12:10:14 (1780330214) [ 7210.015037] LustreError: 306403:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 7212.104145] LustreError: 306403:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 7218.688570] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 12:10:23 (1780330223) [ 7240.544190] Lustre: 300292:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780330231/real 1780330231] req@ffff944b25774380 x1866811270295680/t0(0) o4->lustre-OST0000-osc-ffff944b08002000@192.168.202.156@tcp:6/4 lens 4584/448 e 0 to 1 dl 1780330247 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7240.576777] Lustre: lustre-OST0000-osc-ffff944b08002000: Connection to lustre-OST0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7240.621818] Lustre: lustre-OST0000-osc-ffff944b08002000: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 7257.056205] Lustre: 300292:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780330247/real 1780330247] req@ffff944b25774380 x1866811270295680/t0(0) o4->lustre-OST0000-osc-ffff944b08002000@192.168.202.156@tcp:6/4 lens 4584/448 e 0 to 1 dl 1780330263 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7257.087056] Lustre: 300292:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [ 7257.103617] Lustre: lustre-OST0000-osc-ffff944b08002000: Connection to lustre-OST0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7257.130308] Lustre: lustre-OST0000-osc-ffff944b08002000: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 7273.441906] Lustre: 300292:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780330263/real 1780330263] req@ffff944b25774380 x1866811270295680/t0(0) o4->lustre-OST0000-osc-ffff944b08002000@192.168.202.156@tcp:6/4 lens 4584/448 e 0 to 1 dl 1780330279 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7273.471693] Lustre: 300292:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 7273.480879] Lustre: lustre-OST0000-osc-ffff944b08002000: Connection to lustre-OST0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7273.534968] Lustre: lustre-OST0000-osc-ffff944b08002000: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 7294.588503] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff944b08002000.ost_server_uuid 50 [ 7295.888090] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff944b08002000.ost_server_uuid in FULL state after 0 sec [ 7297.250430] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 7298.686808] Lustre: DEBUG MARKER: == sanityn test complete, duration 7066 sec ============== 12:11:43 (1780330303) [ 7300.075705] Lustre: DEBUG MARKER: === sanityn: start cleanup 12:11:45 (1780330305) === [ 7493.335470] LustreError: 308418:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff944b0b932000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7493.355290] Lustre: Unmounted lustre-client [ 7495.480988] Lustre: DEBUG MARKER: === sanityn: finish cleanup 12:15:01 (1780330501) === [ 7496.152993] LustreError: 308720:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff944b08002000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7496.158821] LustreError: 308720:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 7496.193098] Lustre: Unmounted lustre-client [ 7527.018472] Key type lgssc unregistered [ 7527.161878] LNet: 309202:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7527.165338] LNetError: 309202:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7527.174202] LNet: Removed LNI 192.168.202.56@tcp [ 7527.518215] Key type .llcrypt unregistered [ 7527.520622] Key type ._llcrypt unregistered