[ 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 476681455 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: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.002251] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004078] kvm-guest: setup PV IPIs [ 0.007390] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009009] pid_max: default: 32768 minimum: 301 [ 0.010178] LSM: Security Framework initializing [ 0.011037] Yama: becoming mindful. [ 0.012027] SELinux: Initializing. [ 0.013107] *** VALIDATE selinux *** [ 0.021534] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026594] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027149] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028099] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029113] *** VALIDATE tmpfs *** [ 0.031284] *** VALIDATE proc *** [ 0.033156] *** VALIDATE cgroup *** [ 0.034000] *** VALIDATE cgroup2 *** [ 0.034188] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035167] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036016] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037035] Spectre V2 : User space: Vulnerable [ 0.038013] Speculative Store Bypass: Vulnerable [ 0.040000] debug: unmapping init [mem 0xffffffff9b059000-0xffffffff9b060fff] [ 0.042000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.042768] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.043018] ... version: 2 [ 0.044010] ... bit width: 48 [ 0.045010] ... generic registers: 4 [ 0.046009] ... value mask: 0000ffffffffffff [ 0.047010] ... max period: 00007fffffffffff [ 0.048010] ... fixed-purpose events: 3 [ 0.049010] ... event mask: 000000070000000f [ 0.051200] rcu: Hierarchical SRCU implementation. [ 0.053442] smp: Bringing up secondary CPUs ... [ 0.054591] x86: Booting SMP configuration: [ 0.055022] .... node #0, CPUs: #1 #2 #3 [ 0.063174] smp: Brought up 1 node, 4 CPUs [ 0.065016] smpboot: Max logical packages: 1 [ 0.066012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.136742] node 0 deferred pages initialised in 67ms [ 0.141009] devtmpfs: initialized [ 0.142215] x86/mm: Memory block size: 128MB [ 0.145024] gcov: version magic: 0x41383552 [ 0.147559] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.150102] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.156326] pinctrl core: initialized pinctrl subsystem [ 0.161275] [ 0.161920] ************************************************************* [ 0.175015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.178010] ** ** [ 0.182016] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.185009] ** ** [ 0.187008] ** This means that this kernel is built to expose internal ** [ 0.190063] ** IOMMU data structures, which may compromise security on ** [ 0.192009] ** your system. ** [ 0.195008] ** ** [ 0.197008] ** If you see this message and you are not debugging the ** [ 0.199011] ** kernel, report this immediately to your vendor! ** [ 0.203012] ** ** [ 0.205011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.208008] ************************************************************* [ 0.210399] NET: Registered protocol family 16 [ 0.212424] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.215049] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.217042] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.221149] cpuidle: using governor menu [ 0.223599] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.226698] PCI: Using configuration type 1 for base access [ 0.228178] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.238064] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.240019] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.244114] cryptd: max_cpu_qlen set to 1000 [ 0.248297] ACPI: Added _OSI(Module Device) [ 0.249023] ACPI: Added _OSI(Processor Device) [ 0.250010] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.252014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.258034] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.266550] ACPI: Interpreter enabled [ 0.268059] ACPI: PM: (supports S0 S3 S4 S5) [ 0.270012] ACPI: Using IOAPIC for interrupt routing [ 0.272113] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.277442] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.292243] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.294040] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.297022] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.301170] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.307772] acpiphp: Slot [2] registered [ 0.309182] acpiphp: Slot [5] registered [ 0.311154] acpiphp: Slot [6] registered [ 0.312169] acpiphp: Slot [3] registered [ 0.314098] acpiphp: Slot [4] registered [ 0.316119] acpiphp: Slot [7] registered [ 0.317146] acpiphp: Slot [8] registered [ 0.319093] acpiphp: Slot [9] registered [ 0.321116] acpiphp: Slot [10] registered [ 0.322114] acpiphp: Slot [11] registered [ 0.324124] acpiphp: Slot [12] registered [ 0.325099] acpiphp: Slot [13] registered [ 0.326110] acpiphp: Slot [14] registered [ 0.328153] acpiphp: Slot [15] registered [ 0.329165] acpiphp: Slot [16] registered [ 0.331312] acpiphp: Slot [17] registered [ 0.333126] acpiphp: Slot [18] registered [ 0.335110] acpiphp: Slot [19] registered [ 0.336243] acpiphp: Slot [20] registered [ 0.338243] acpiphp: Slot [21] registered [ 0.340151] acpiphp: Slot [22] registered [ 0.342162] acpiphp: Slot [23] registered [ 0.344112] acpiphp: Slot [24] registered [ 0.346097] acpiphp: Slot [25] registered [ 0.348118] acpiphp: Slot [26] registered [ 0.349092] acpiphp: Slot [27] registered [ 0.351150] acpiphp: Slot [28] registered [ 0.353104] acpiphp: Slot [29] registered [ 0.354088] acpiphp: Slot [30] registered [ 0.356157] acpiphp: Slot [31] registered [ 0.357155] PCI host bridge to bus 0000:00 [ 0.359018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.361017] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.364051] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.367023] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.370016] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.373024] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.375187] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.379163] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.383635] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.392021] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.397837] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.401015] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.403012] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.406020] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.409665] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.412816] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.416031] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.418784] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.422911] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.431877] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.437014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.444263] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.453013] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.458013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.471020] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.484135] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.499014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.511012] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.525013] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.535391] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.539901] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.543482] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.545346] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.547264] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.553026] iommu: Default domain type: Passthrough [ 0.555467] SCSI subsystem initialized [ 0.556136] ACPI: bus type USB registered [ 0.558136] usbcore: registered new interface driver usbfs [ 0.560058] usbcore: registered new interface driver hub [ 0.561049] usbcore: registered new device driver usb [ 0.562127] pps_core: LinuxPPS API ver. 1 registered [ 0.563007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.565055] PTP clock support registered [ 0.567079] EDAC MC: Ver: 3.0.0 [ 0.570133] PCI: Using ACPI for IRQ routing [ 0.571735] NetLabel: Initializing [ 0.574009] NetLabel: domain hash size = 128 [ 0.575007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.578079] NetLabel: unlabeled traffic allowed by default [ 0.580269] vgaarb: loaded [ 0.582360] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.584011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.590129] clocksource: Switched to clocksource kvm-clock [ 0.704842] VFS: Disk quotas dquot_6.6.0 [ 0.706097] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.708290] *** VALIDATE ramfs *** [ 0.709277] *** VALIDATE hugetlbfs *** [ 0.710540] pnp: PnP ACPI init [ 0.712524] pnp: PnP ACPI: found 6 devices [ 0.727176] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.729851] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.732046] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.735082] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.738249] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.743886] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.748358] NET: Registered protocol family 2 [ 0.751073] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.756195] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.759708] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.764903] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.767771] TCP: Hash tables configured (established 65536 bind 65536) [ 0.770922] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.774074] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.777284] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.780711] NET: Registered protocol family 1 [ 0.782849] RPC: Registered named UNIX socket transport module. [ 0.784780] RPC: Registered udp transport module. [ 0.786525] RPC: Registered tcp transport module. [ 0.788397] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.790888] NET: Registered protocol family 44 [ 0.792681] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.795048] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.797278] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.799779] PCI: CLS 0 bytes, default 64 [ 0.801576] Unpacking initramfs... [ 2.437701] debug: unmapping init [mem 0xffff9045fcc64000-0xffff9045fffcffff] [ 2.442909] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.444948] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.447413] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.101398] Initialise system trusted keyrings [ 3.103208] Key type blacklist registered [ 3.105518] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.115737] zbud: loaded [ 3.119087] *** VALIDATE nfs *** [ 3.120598] *** VALIDATE nfs4 *** [ 3.122290] pstore: using deflate compression [ 3.126199] Platform Keyring initialized [ 3.236702] NET: Registered protocol family 38 [ 3.239203] Key type asymmetric registered [ 3.240566] Asymmetric key parser 'x509' registered [ 3.242773] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.245909] io scheduler mq-deadline registered [ 3.247757] io scheduler kyber registered [ 3.249487] io scheduler bfq registered [ 3.251294] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.254220] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.256523] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.259712] ACPI: Power Button [PWRF] [ 3.265334] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.272225] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.297378] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.326906] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.355894] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.360691] Non-volatile memory driver v1.3 [ 3.362297] Linux agpgart interface v0.103 [ 3.387421] virtio_blk virtio1: [vda] 149768 512-byte logical blocks (76.7 MB/73.1 MiB) [ 3.389751] vda: detected capacity change from 0 to 76681216 [ 3.416104] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.422894] vdb: detected capacity change from 0 to 1073741824 [ 3.428955] libphy: Fixed MDIO Bus: probed [ 3.443649] usbcore: registered new interface driver usbserial_generic [ 3.447190] usbserial: USB Serial support registered for generic [ 3.449505] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.453791] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.456202] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.459358] mousedev: PS/2 mouse device common for all mice [ 3.461869] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.464323] rtc_cmos 00:05: RTC can wake from S4 [ 3.470642] rtc_cmos 00:05: registered as rtc0 [ 3.472408] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.475219] intel_pstate: CPU model not supported [ 3.478613] hid: raw HID events driver (C) Jiri Kosina [ 3.480875] usbcore: registered new interface driver usbhid [ 3.483597] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.485683] usbhid: USB HID core driver [ 3.487545] drop_monitor: Initializing network drop monitor service [ 3.495367] Initializing XFRM netlink socket [ 3.497527] NET: Registered protocol family 10 [ 3.500958] Segment Routing with IPv6 [ 3.502749] NET: Registered protocol family 17 [ 3.503623] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.504932] mpls_gso: MPLS GSO support [ 3.515748] RAS: Correctable Errors collector initialized. [ 3.517979] AVX version of gcm_enc/dec engaged. [ 3.519758] AES CTR mode by8 optimization enabled [ 3.601752] sched_clock: Marking stable (3601679914, 0)->(4459032227, -857352313) [ 3.607420] registered taskstats version 1 [ 3.609847] Loading compiled-in X.509 certificates [ 3.612358] zswap: loaded using pool lzo/zbud [ 3.638042] Key type big_key registered [ 3.663513] Key type encrypted registered [ 3.665237] ima: No TPM chip found, activating TPM-bypass! [ 3.667138] ima: Allocated hash algorithm: sha1 [ 3.669591] ima: No architecture policies found [ 3.671757] evm: Initialising EVM extended attributes: [ 3.674165] evm: security.selinux [ 3.675745] evm: security.ima [ 3.677134] evm: security.capability [ 3.678881] evm: HMAC attrs: 0x1 [ 3.681766] rtc_cmos 00:05: setting system clock to 2026-09-09 01:45:02 UTC (1788918302) [ 3.688821] debug: unmapping init [mem 0xffffffff9c003000-0xffffffff9c1fffff] [ 3.692775] debug: unmapping init [mem 0xffffffff9ad82000-0xffffffff9b058fff] [ 3.705149] Write protecting the kernel read-only data: 28672k [ 3.727795] debug: unmapping init [mem 0xffffffff99403000-0xffffffff995fffff] [ 3.732413] debug: unmapping init [mem 0xffffffff99d14000-0xffffffff99dfffff] [ 3.790902] 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.803521] systemd[1]: Detected virtualization kvm. [ 3.806211] systemd[1]: Detected architecture x86-64. [ 3.808863] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.895592] systemd[1]: No hostname configured. [ 3.898802] systemd[1]: Set hostname to . [ 3.901609] random: systemd: uninitialized urandom read (16 bytes read) [ 3.906274] systemd[1]: Initializing machine ID from random generator. [ 4.575832] random: systemd: uninitialized urandom read (16 bytes read) [ 4.587617] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 4.616271] random: systemd: uninitialized urandom read (16 bytes read) [ 4.624862] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 4.661386] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Timers. [ OK ] Reached target Swap. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.606704] device-mapper: uevent: version 1.0.3 [ 5.609557] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 7.367674] random: fast init done [ 7.500837] virtio_net virtio0 ens2: renamed from eth0 [ 7.823195] scsi host0: ata_piix [ 7.909625] scsi host1: ata_piix [ 7.913262] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.918962] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.188229] random: crng init done [ 12.191987] random: 7 urandom warning(s) missed due to ratelimiting [ 14.258736] dracut-initqueue[583]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ 16.155362] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Reached target Remote File Systems. [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ 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 Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 18.701861] printk: systemd: 26 output lines suppressed due to ratelimiting [ 19.463588] SELinux: Disabled at runtime. [ 19.591632] 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) [ 19.608917] systemd[1]: Detected virtualization kvm. [ 19.612883] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 20.852901] systemd[1]: initrd-switch-root.service: Succeeded. [ 20.859421] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 20.881589] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 20.890553] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 20.901688] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 20.929830] systemd[1]: Starting Journal Service... Starting Journal Service... [ 20.957398] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Process Core Dump Socket. Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Control Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting POSIX Message Queue File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Create list of required st…ce nodes for the current kernel... Mounting Kernel Debug File System... [ OK ] Listening on initctl Compatibility Named Pipe. Starting Apply Kernel Variables... [ 21.360380] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target rpc_pipefs.target. Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue 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 Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 22.412249] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 23.383981] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 23.450246] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 24.051353] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 24.143029] EDAC sbridge: Ver: 1.1.2 [ 27.558147] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit)[ 28.398144] NFS: Registering the id_resolver key type [ 28.401120] Key type id_resolver registered [ 28.402766] Key type id_legacy registered [** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. [ 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... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ 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 ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Restore /run/initramfs on shutdown... Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ 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 GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Login Service. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg326-client login: [ 66.695249] libcfs: loading out-of-tree module taints kernel. [ 66.786224] Key type ._llcrypt registered [ 66.787596] Key type .llcrypt registered [ 67.021459] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 67.028441] alg: No test for adler32 (adler32-zlib) [ 68.062392] Lustre: Lustre: Build Version: 2.17.58_2_g8df9471 [ 68.416979] LNet: Added LNI 192.168.203.26@tcp [8/256/0/180] [ 70.047169] Key type lgssc registered [ 70.806406] Lustre: Echo OBD driver; http://www.lustre.org/ [ 155.819339] hrtimer: interrupt took 4719182 ns [ 209.948184] Lustre: Mounted lustre-client - version 2.17.58_2_g8df9471 [ 216.279438] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 235.492896] Lustre: lustre-OST0000-osc-ffff904651178000: disconnect after 23s idle [ 236.645824] Lustre: DEBUG MARKER: oleg326-client.virtnet: executing check_logdir /tmp/testlogs/ [ 241.755781] Lustre: DEBUG MARKER: oleg326-client.virtnet: executing yml_node [ 245.534230] Lustre: DEBUG MARKER: Client: 2.17.58.2 [ 247.457520] Lustre: DEBUG MARKER: MDS: 2.17.58.2 [ 249.954616] Lustre: DEBUG MARKER: OSS: 2.17.58.2 [ 251.412501] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Tue Sep 8 21:49:08 EDT 2026 [ 266.597706] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 267.959336] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 269.304489] Lustre: DEBUG MARKER: === sanityn: start setup 21:49:26 (1788918566) === [ 269.761581] Lustre: Mounted lustre-client - version 2.17.58_2_g8df9471 [ 275.130550] Lustre: DEBUG MARKER: oleg326-client.virtnet: executing check_config_client /mnt/lustre [ 292.475840] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 304.454551] Lustre: DEBUG MARKER: === sanityn: finish setup 21:50:02 (1788918602) === [ 306.174798] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 21:50:03 (1788918603) [ 313.148413] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 21:50:10 (1788918610) [ 319.416833] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 21:50:17 (1788918617) [ 326.978873] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 21:50:24 (1788918624) [ 333.652053] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 21:50:30 (1788918630) [ 340.013814] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 21:50:37 (1788918637) [ 345.958164] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 21:50:43 (1788918643) [ 351.653926] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 21:50:49 (1788918649) [ 358.222242] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 21:50:55 (1788918655) [ 364.539342] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 21:51:01 (1788918661) [ 372.781224] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 21:51:10 (1788918670) [ 379.821107] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 21:51:17 (1788918677) [ 382.433375] Lustre: lustre-OST0000-osc-ffff904651178000: disconnect after 24s idle [ 382.442754] Lustre: Skipped 1 previous similar message [ 385.219366] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 21:51:23 (1788918683) [ 391.298644] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 21:51:28 (1788918688) [ 397.791268] Lustre: lustre-OST0001-osc-ffff904651f56000: disconnect after 24s idle [ 398.217705] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 21:51:35 (1788918695) [ 404.742443] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 21:51:42 (1788918702) [ 412.048856] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 21:51:49 (1788918709) [ 420.385526] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 21:51:57 (1788918717) [ 428.838850] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 21:52:06 (1788918726) [ 435.624217] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 21:52:13 (1788918733) [ 442.037457] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 21:52:19 (1788918739) [ 442.935941] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 584.292210] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 21:54:42 (1788918882) [ 595.519277] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 21:54:52 (1788918892) [ 605.511367] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 21:55:02 (1788918902) [ 614.957387] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 21:55:11 (1788918911) [ 622.564407] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 21:55:19 (1788918919) [ 630.366621] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 21:55:27 (1788918927) [ 632.464919] Lustre: DEBUG MARKER: chmod [ 639.849726] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 21:55:37 (1788918937) [ 1291.789615] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 22:06:29 (1788919589) [ 1370.591562] Lustre: lustre-OST0001-osc-ffff904651f56000: disconnect after 21s idle [ 1385.602466] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 22:08:03 (1788919683) [ 1429.887230] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 22:08:48 (1788919728) [ 1485.800930] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 22:09:44 (1788919784) [ 1499.920726] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 22:09:58 (1788919798) [ 1502.651420] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 22:10:00 (1788919800) [ 1503.077773] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.106767] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.133608] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.158311] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.186769] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.213882] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.239544] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.262592] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.285641] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.314407] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.348687] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.381093] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.408336] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.437836] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.466800] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.494893] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.525052] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.552325] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.583248] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.606413] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.636338] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.661380] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.687278] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.718318] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.749877] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.775457] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.801628] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.829445] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.857248] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.883198] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.912236] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.942556] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.967720] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1503.992689] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.022708] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.051844] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.079668] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.106833] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.141897] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.167838] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.194512] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.223751] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.252883] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.283813] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.311381] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.334601] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.357763] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.384347] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.413506] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.442937] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.470177] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.497309] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.525547] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.552813] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.579658] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.609214] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.637161] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.669160] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.698037] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.723946] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.752250] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.783308] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.809578] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.843532] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.874284] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.906170] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.939893] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.964413] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1504.991412] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.017474] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.047866] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.074041] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.104435] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.129834] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.153821] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.183407] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.216258] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.241570] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.270623] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.298106] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.326754] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.355527] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.380963] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.404510] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.432727] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.460115] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.488707] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.523750] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.554674] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.583542] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.609887] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.638484] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.668282] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.697580] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.726236] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.749744] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.773929] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.804806] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.832458] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.861697] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.900347] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.932798] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.957208] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1505.984658] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.011240] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.040970] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.078938] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.109988] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.140824] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.170236] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.194202] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.225386] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.257244] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.288664] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.314084] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.342789] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.364174] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.392444] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.420349] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.454531] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.481441] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.514579] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.541552] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.569424] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.598046] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.629666] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.660065] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.686058] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.714862] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.744386] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.773238] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.809487] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.840609] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.871748] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.903927] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.934474] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.962424] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1506.987964] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.021595] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.054541] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.093333] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.122489] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.148231] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.172445] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.195579] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.223894] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.256489] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.285280] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.314741] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.353043] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.399140] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.433801] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.466663] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.496585] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.527892] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.557092] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.584408] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.623521] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.653654] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.689892] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.720795] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.754420] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.785688] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.821278] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.850033] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.880651] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.908353] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.937595] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.964947] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1507.991811] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.019057] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.047903] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.074820] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.101620] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.125723] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.153486] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.180164] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.211637] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.249421] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.275516] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.302939] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.328989] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.356707] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.382444] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.415731] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.445126] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.472234] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.497106] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.521268] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.545244] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.576942] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.607615] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.639295] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.667757] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.694665] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.720076] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.746183] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.769946] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.796169] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.827727] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.856663] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.883381] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.912433] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.939143] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1508.971868] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.005932] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.031272] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.064385] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.088809] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.115411] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.138612] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.161482] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.187585] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.216957] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.246402] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.277636] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.303811] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.326133] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.358910] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.385487] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.409209] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.441935] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.471482] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.495535] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.521133] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.551930] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.584814] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.611094] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.637151] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.661248] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.686525] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.712102] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.742796] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.774210] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.803916] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.837673] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.870549] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.895469] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.923937] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.953331] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1509.984139] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.012910] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.042798] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.076156] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.107992] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.137810] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.163981] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.187397] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.219743] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.245847] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.270910] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.300847] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.327373] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.353377] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.378255] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.404429] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.431913] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.456674] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.482163] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.507354] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.534224] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.558399] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.595971] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.623979] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.647151] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.681815] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.705723] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.730642] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.767819] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.793399] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.821123] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.849828] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.876048] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.901104] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.930612] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.952989] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1510.977585] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.003040] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.026825] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.050661] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.079921] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.113275] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.140441] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.166871] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.189474] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.215071] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.244344] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.272967] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.300534] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.328970] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.355621] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.383864] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.405140] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.431441] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.456207] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.481227] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.506051] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.534301] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.564204] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.593666] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.618087] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.642827] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.669869] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.700844] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.726236] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.753785] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.778955] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.803375] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.831050] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.854569] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.889595] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.916866] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.939412] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.965743] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1511.988874] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.019670] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.047098] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.072362] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.098199] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.122939] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.154200] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.181160] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.207084] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.246350] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.273738] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.297444] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.325262] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.358816] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.385418] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.411193] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.436310] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.462406] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.488569] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.515052] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.542175] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.567310] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.592681] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.615605] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.640319] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.667541] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.691431] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.727374] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.764470] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.795353] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.822623] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.851316] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.879559] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.903755] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.934281] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.960744] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1512.987980] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.017614] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.047788] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.074033] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.103839] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.133085] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.161211] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.189241] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.215600] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.245583] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.268632] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.294209] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.320218] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.349197] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.379509] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.409632] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.434923] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.463449] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.491492] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.516991] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.545317] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.575062] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.604096] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.628913] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.655093] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.680660] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.711283] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.734784] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.764300] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.793078] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.820179] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.847628] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.876978] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.901845] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.929864] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.956900] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1513.985233] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.013130] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.039355] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.069711] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.095581] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.123739] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.150684] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.174028] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.202230] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.232353] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.266731] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.296909] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.324182] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.349119] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.373288] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.403950] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.430795] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.457265] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.488181] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.519327] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.547276] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.575125] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.601231] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.642595] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.670241] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.696745] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.720761] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.747145] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.771768] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.797107] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.824199] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.856282] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.884716] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.908264] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.937792] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.966450] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1514.994516] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.021713] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.050128] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.078600] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.105418] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.131558] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.155596] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.181149] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.207861] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.229642] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.255770] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.278765] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.304090] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.331729] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.359903] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.390105] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.416486] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.443888] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.471979] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.496754] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.521878] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.545642] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.572810] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.607363] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.637230] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.663124] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.687956] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.711853] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.735199] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.762399] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.786056] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.808875] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.834395] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.857947] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.880826] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.906117] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.934643] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.960420] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1515.986735] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.011128] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.046882] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.076435] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.114526] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.141912] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.177128] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.201276] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.224968] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.254954] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.283094] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.309656] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.332650] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.361850] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.392796] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.421466] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.455418] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.483907] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.517412] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.555088] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.601751] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.633054] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.662252] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.686157] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.715400] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.742231] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.776364] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.822817] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.858054] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.887585] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.918611] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.943251] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1516.981163] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.016795] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.043047] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.072193] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.099369] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.134206] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.168261] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.206614] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.261989] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.304674] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.350624] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.387507] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.426758] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.458925] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.497693] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.545362] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.577216] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.607571] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.648733] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.680120] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.719379] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.750766] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.778844] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.808741] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.848742] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.886712] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.916217] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.967799] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1517.997264] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.031855] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.070198] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.106472] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.136745] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.184561] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.230664] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.271641] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.309694] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.353745] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.381248] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.414541] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.441919] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.467456] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.505195] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.535588] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.587359] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.621396] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.651701] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.680927] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.712712] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.742281] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.778571] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.802774] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.831751] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.860135] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.891608] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.921505] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.952264] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1518.981300] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.010761] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.037913] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.063780] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.093957] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.134477] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.174839] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.202762] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.229129] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.257864] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.283387] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.308290] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.332899] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.362256] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.391806] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.423480] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.453115] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.477466] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.501431] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.530243] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.565260] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.602986] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.632897] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.662249] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.690800] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.722156] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.750519] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.782481] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.809891] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.836231] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.861168] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.884808] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.910921] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.940823] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.966480] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1519.991651] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.017273] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.044418] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.074028] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.103695] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.127508] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.153089] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.178080] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.204417] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.230225] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.258251] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.282368] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.308997] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.334851] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.364682] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.395690] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.430879] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.464255] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.493210] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.518724] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.549088] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.578383] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.611487] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.637372] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.664808] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.691258] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.720760] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.755041] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.784213] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.810200] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.836934] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.872295] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.902667] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.929425] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.957128] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1520.982311] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.009407] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.034213] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.059307] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.091683] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.117989] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.152048] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.174601] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.204172] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.237596] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.269260] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.297313] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.325271] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.351116] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.386836] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.417732] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.446266] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.474158] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.500550] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.527478] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.550335] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.576759] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.603913] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.628977] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.653924] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.679211] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.702554] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.727582] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.752884] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.789551] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.819627] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.847727] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.875690] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.908379] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.942188] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1521.978662] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.007689] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.034508] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.066135] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.098705] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.126326] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.156542] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.185300] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.211168] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.237554] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.264875] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.290906] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.324436] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.351352] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.381878] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.410782] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.438834] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.464544] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.495907] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.523578] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.554698] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.583938] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.609603] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.635711] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.663366] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1522.695873] rw_seq_cst_vs_d (32566): drop_caches: 3 [ 1524.191366] Lustre: lustre-OST0000-osc-ffff904651178000: disconnect after 23s idle [ 1525.756790] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 22:10:23 (1788919823) [ 1525.908434] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1525.940892] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1525.959093] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1525.978293] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.055911] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.085487] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.103521] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.122215] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.140231] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.166873] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.302453] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.325466] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.348922] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.419516] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.459811] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.514831] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.537093] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.558448] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.593086] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.616396] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.690572] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.708727] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.728050] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.746047] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.787507] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.807826] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.847229] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1526.927089] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.084710] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.102049] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.119261] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.165501] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.401179] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.452517] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.518848] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.540509] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.592112] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.610673] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.629955] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.686774] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.757605] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.784874] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.814572] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.833938] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.852174] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.893166] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.941090] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.959569] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1527.981465] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.041479] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.133758] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.185339] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.381857] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.410502] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.437513] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.454771] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.530844] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.552641] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.603794] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.622222] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.643098] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.694080] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.762778] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.781426] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1528.829233] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.074875] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.097733] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.118330] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.135377] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.152299] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.190717] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.231438] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.249763] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.282640] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.320778] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.361919] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.406769] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.596129] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.614492] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.684149] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.700225] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.717574] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.764015] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.791766] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.838353] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.861637] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.906755] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.923336] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1529.986387] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.013719] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.178238] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.203280] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.225380] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.258162] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.303311] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.327235] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.380170] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.453419] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.501858] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.544029] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.561314] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.636034] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.658974] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.680158] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.698558] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.746606] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.774490] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.839066] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.858689] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.885455] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.906443] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.954830] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1530.981695] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.044297] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.084938] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.123489] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.145353] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.162228] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.187729] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.209687] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.234868] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.259345] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.282954] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.401927] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.420859] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.465056] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.491956] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.518179] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.537966] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.567825] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.613254] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.632891] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.650096] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.669112] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.689638] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.708954] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.732379] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.751165] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1531.768626] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.200518] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.374765] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.474275] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.495085] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.529755] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.619977] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.640215] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.788562] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.819596] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.849992] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.875992] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1532.932315] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1533.109949] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1533.127905] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1533.234648] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1533.437536] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1533.461104] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1533.489278] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1533.516767] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1533.537229] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1533.566554] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1533.674599] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1533.721921] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.026099] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.043453] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.070518] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.090406] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.111306] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.130096] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.151631] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.292917] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.380636] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.586374] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.611635] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.637036] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.740271] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.790328] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1534.901227] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.059625] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.086291] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.116903] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.143391] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.182352] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.290133] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.310521] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.381411] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.408265] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.470571] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.491086] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.566111] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.621788] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.640258] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.664322] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.707481] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.808273] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.840867] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.926113] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.947410] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1535.978977] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.000445] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.019399] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.056534] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.077373] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.095429] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.113432] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.325388] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.653643] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.683262] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.751963] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.836962] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1536.907511] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.002603] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.027885] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.051707] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.078537] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.103834] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.127357] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.145464] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.165303] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.196130] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.254219] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.280110] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.868209] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.939288] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1537.962485] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.013515] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.038209] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.064168] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.083459] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.104796] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.121610] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.139237] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.192032] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.213647] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.243444] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.310164] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.337410] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.362726] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.403437] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.425151] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.452807] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.567216] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.589408] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.670922] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.692992] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.858368] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.875990] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.933681] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1538.956113] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.077225] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.097292] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.116014] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.138281] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.158863] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.180016] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.200571] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.220658] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.243224] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.261935] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.280192] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.374471] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.549634] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.593328] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.702409] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.727983] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.747175] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.792785] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.835040] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.861562] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.941410] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1539.961492] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.060788] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.081745] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.109739] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.133894] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.166468] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.185600] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.406710] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.428789] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.641375] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.661239] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.679275] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.698132] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.716719] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.751560] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.811372] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.845989] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.866503] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.924936] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.966619] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1540.993786] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.013222] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.053237] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.100283] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.149721] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.220187] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.238697] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.262799] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.284787] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.325699] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.409283] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.428788] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.450880] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.475752] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.498298] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.517108] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.549965] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.576422] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.596362] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.672076] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.808146] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.854756] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.872475] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.941308] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.960941] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1541.991375] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.035500] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.052282] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.075848] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.104249] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.122020] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.146289] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.227329] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.268715] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.376740] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.397408] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.417732] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.532577] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.557929] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.625343] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.644305] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.662066] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.710287] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.728848] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.757574] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.780876] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.800580] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.899409] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.926586] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.945262] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1542.971562] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.085137] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.103223] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.124380] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.153674] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.178958] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.197216] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.234595] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.252382] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.271890] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.357909] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.401729] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.445489] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.471438] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.495693] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.513584] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.531914] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.560851] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.577811] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.600773] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.619096] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.636993] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.653154] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.674192] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.747682] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.767856] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.785636] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.834750] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.856095] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.876588] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.910440] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1543.963732] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.034427] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.052931] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.081528] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.109303] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.141412] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.160697] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.178134] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.260590] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.554240] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.606668] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.674864] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.812634] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.852898] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.870829] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.909692] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1544.934961] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.015535] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.035453] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.052449] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.079970] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.099053] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.149335] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.166597] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.184736] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.203395] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.272422] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.349486] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.371346] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.413989] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.460126] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.493650] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.512709] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.530996] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.554258] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.599066] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.641500] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.659265] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.693114] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.786036] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1545.808155] rw_seq_cst_vs_d (33151): drop_caches: 3 [ 1549.013100] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 22:10:47 (1788919847) [ 1551.973787] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 22:10:50 (1788919850) [ 1554.746153] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 22:10:52 (1788919852) [ 1564.932349] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 22:11:03 (1788919863) [ 1565.158395] bash (35630): drop_caches: 3 [ 1568.249649] bash (35630): drop_caches: 3 [ 1571.300061] bash (35630): drop_caches: 3 [ 1574.396492] bash (35630): drop_caches: 3 [ 1578.016071] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 22:11:16 (1788919876) [ 1583.163293] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 22:11:21 (1788919881) [ 1600.140229] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 22:11:38 (1788919898) [ 1603.702454] Lustre: DEBUG MARKER: loop 5 [ 1605.660886] Lustre: DEBUG MARKER: loop 10 [ 1607.571153] Lustre: DEBUG MARKER: loop 15 [ 1609.503734] Lustre: DEBUG MARKER: loop 20 [ 1612.502494] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 22:11:50 (1788919910) [ 1615.072466] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 22:11:53 (1788919913) [ 1617.606197] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 22:11:55 (1788919915) [ 1636.831702] Lustre: lustre-OST0001-osc-ffff904651178000: disconnect after 24s idle [ 1636.834242] Lustre: Skipped 1 previous similar message [ 1641.952164] Lustre: lustre-OST0000-osc-ffff904651178000: disconnect after 24s idle [ 1641.961545] Lustre: Skipped 2 previous similar messages [ 1685.248943] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 22:13:02 (1788919982) [ 1691.583122] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 22:13:09 (1788919989) [ 1698.581595] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 22:13:16 (1788919996) [ 1705.333726] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 22:13:22 (1788920002) [ 1712.291537] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 22:13:30 (1788920010) [ 1719.813938] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 22:13:37 (1788920017) [ 1728.817383] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 22:13:46 (1788920026) [ 1737.207772] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1738.504492] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 22:13:56 (1788920036) [ 1746.874917] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 22:14:04 (1788920044) [ 1747.366497] Lustre: *** cfs_fail_loc=314, val=0*** [ 1748.448353] Lustre: *** cfs_fail_loc=314, val=0*** [ 1748.450177] Lustre: Skipped 2 previous similar messages [ 1749.475924] Lustre: lustre-OST0000-osc-ffff904651178000: disconnect after 20s idle [ 1753.827107] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 22:14:11 (1788920051) [ 1762.452485] Lustre: *** cfs_fail_loc=314, val=0*** [ 1762.529289] LustreError: lustre-OST0000-osc-ffff904651f56000: operation ldlm_enqueue to node 192.168.203.126@tcp failed: rc = -107 [ 1762.540367] Lustre: lustre-OST0000-osc-ffff904651f56000: Connection to lustre-OST0000 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1762.557779] LustreError: lustre-OST0000-osc-ffff904651f56000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1762.570051] Lustre: 2395:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.126@tcp:/lustre/fid: [0x240000403:0x2:0x0]// may get corrupted (rc -108) [ 1762.589896] LustreError: 46542:0:(ldlm_resource.c:1207:ldlm_resource_complain()) lustre-OST0000-osc-ffff904651f56000: namespace resource [0x280000400:0x8:0x0].0x0 (ffff90464619f600) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1762.611269] Lustre: lustre-OST0000-osc-ffff904651f56000: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [ 1768.054508] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 22:14:25 (1788920065) [ 1768.269659] LustreError: 47132:0:(file.c:850:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1771.295129] LustreError: 47132:0:(file.c:850:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1775.583200] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 22:14:33 (1788920073) [ 1781.437191] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 22:14:39 (1788920079) [ 1787.526314] Lustre: DEBUG MARKER: == sanityn test 32b: lockless i/o ======================== 22:14:45 (1788920085) [ 1788.776039] Lustre: DEBUG MARKER: SKIP: sanityn test_32b max_nolock_bytes is removed >= 2.17.53 [ 1789.991250] Lustre: DEBUG MARKER: == sanityn test 32c: contention detection ================ 22:14:47 (1788920087) [ 1812.090520] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1813.520906] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 22:15:11 (1788920111) [ 1814.861330] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1816.267193] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 22:15:13 (1788920113) [ 1821.155447] Lustre: lustre-MDT0000-mdc-ffff904651178000: Connection to lustre-MDT0000 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1826.271656] Lustre: lustre-OST0001-osc-ffff904651178000: disconnect after 25s idle [ 1826.294678] LustreError: MGC192.168.203.126@tcp: Connection to MGS (at 192.168.203.126@tcp) was lost; in progress operations using this service will fail [ 1826.306074] Lustre: Evicted from MGS (at 192.168.203.126@tcp) after server handle changed from 0x7900fb4a2b2bc1e4 to 0x7900fb4a2b375052 [ 1826.317717] Lustre: MGC192.168.203.126@tcp: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [ 1829.311512] Lustre: lustre-MDT0000-mdc-ffff904651f56000: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [ 1851.032968] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 22:15:49 (1788920149) [ 1862.112149] Lustre: lustre-OST0000-osc-ffff904651f56000: disconnect after 23s idle [ 1862.115235] Lustre: Skipped 3 previous similar messages [ 1890.258297] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 22:16:28 (1788920188) [ 1907.205379] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 22:16:44 (1788920204) [ 1918.431313] Lustre: lustre-OST0000-osc-ffff904651f56000: disconnect after 23s idle [ 1918.441605] Lustre: Skipped 3 previous similar messages [ 1958.345547] Lustre: lustre-OST0001-osc-ffff904651178000: Connection to lustre-OST0001 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1958.356663] Lustre: Skipped 2 previous similar messages [ 1958.358422] LustreError: lustre-OST0001-osc-ffff904651f56000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1958.372452] Lustre: lustre-OST0001-osc-ffff904651f56000: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [ 1958.378838] Lustre: Skipped 1 previous similar message [ 1958.379508] LustreError: lustre-OST0001-osc-ffff904651178000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1973.731971] Lustre: lustre-OST0000-osc-ffff904651178000: Connection to lustre-OST0000 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1973.797933] LustreError: lustre-OST0000-osc-ffff904651178000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1973.818265] Lustre: lustre-OST0000-osc-ffff904651178000: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [ 1973.823258] Lustre: Skipped 1 previous similar message [ 1995.231206] Lustre: lustre-OST0000-osc-ffff904651178000: disconnect after 22s idle [ 1995.242805] Lustre: Skipped 3 previous similar messages [ 2000.099800] Lustre: DEBUG MARKER: oleg326-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff904651178000.ost_server_uuid 50 [ 2002.754747] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff904651178000.ost_server_uuid in IDLE state after 0 sec [ 2010.570981] Lustre: DEBUG MARKER: oleg326-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff904651178000.ost_server_uuid 50 [ 2012.847673] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff904651178000.ost_server_uuid in IDLE state after 0 sec [ 2021.510284] Lustre: DEBUG MARKER: oleg326-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff904651178000.ost_server_uuid 50 [ 2023.681496] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff904651178000.ost_server_uuid in IDLE state after 0 sec [ 2029.797387] Lustre: DEBUG MARKER: oleg326-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff904651178000.ost_server_uuid 50 [ 2031.655837] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff904651178000.ost_server_uuid in IDLE state after 0 sec [ 2045.909500] Lustre: DEBUG MARKER: oleg326-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff904651178000.ost_server_uuid 50 [ 2047.394467] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff904651178000.ost_server_uuid in IDLE state after 0 sec [ 2053.412398] Lustre: DEBUG MARKER: oleg326-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff904651178000.ost_server_uuid 50 [ 2054.888696] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff904651178000.ost_server_uuid in IDLE state after 0 sec [ 2056.674078] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 22:19:14 (1788920354) [ 2059.339331] Lustre: DEBUG MARKER: Race attempt 0 [ 2062.558116] Lustre: DEBUG MARKER: Wait for 59727 59749 for 60 sec... [ 2129.914743] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 22:20:27 (1788920427) [ 2137.449571] Lustre: DEBUG MARKER: start test - cycle (0) [ 2159.246553] Lustre: DEBUG MARKER: start test - cycle (1) [ 2186.117533] Lustre: DEBUG MARKER: start test - cycle (2) [ 2211.320197] Lustre: DEBUG MARKER: start test - cycle (3) [ 2215.903679] Lustre: lustre-OST0001-osc-ffff904651f56000: disconnect after 21s idle [ 2234.921757] Lustre: DEBUG MARKER: start test - cycle (4) [ 2257.579723] Lustre: DEBUG MARKER: start test - cycle (5) [ 2281.293831] Lustre: DEBUG MARKER: start test - cycle (6) [ 2303.499369] Lustre: DEBUG MARKER: start test - cycle (7) [ 2327.872225] Lustre: DEBUG MARKER: start test - cycle (8) [ 2349.886900] Lustre: DEBUG MARKER: start test - cycle (9) [ 2375.396250] Lustre: DEBUG MARKER: start test - cycle (10) [ 2406.193492] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 22:25:03 (1788920703) [ 2471.955985] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 22:26:09 (1788920769) [ 2482.367209] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 22:26:19 (1788920779) [ 2489.848944] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 22:26:27 (1788920787) [ 2492.392259] Lustre: lustre-OST0000-osc-ffff904651f56000: disconnect after 20s idle [ 2492.408077] Lustre: Skipped 3 previous similar messages [ 2497.650551] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 22:26:35 (1788920795) [ 2497.927837] Lustre: *** cfs_fail_loc=411, val=0*** [ 2503.221965] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2504.536520] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 22:26:42 (1788920802) [ 2519.947833] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 22:26:57 (1788920817) [ 2534.731626] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 22:27:12 (1788920832) [ 2548.253448] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 22:27:26 (1788920846) [ 2562.001394] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 22:27:39 (1788920859) [ 2572.571478] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 22:27:50 (1788920870) [ 2582.116146] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 22:27:59 (1788920879) [ 2591.940884] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 22:28:09 (1788920889) [ 2601.783478] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 22:28:19 (1788920899) [ 2611.641139] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 22:28:29 (1788920909) [ 2620.998436] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 22:28:38 (1788920918) [ 2629.577869] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 22:28:47 (1788920927) [ 2638.625657] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 22:28:56 (1788920936) [ 3255.263237] Lustre: lustre-OST0000-osc-ffff904651f56000: disconnect after 20s idle [ 3255.266588] Lustre: Skipped 6 previous similar messages [ 3511.347916] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 22:43:28 (1788921808) [ 3528.836433] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 22:43:44 (1788921824) [ 3548.457213] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 22:44:05 (1788921845) [ 3565.545687] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 22:44:23 (1788921863) [ 3578.520457] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 22:44:35 (1788921875) [ 3591.158950] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 22:44:48 (1788921888) [ 3603.004634] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 22:45:00 (1788921900) [ 3614.377581] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 22:45:12 (1788921912) [ 3625.687731] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 22:45:23 (1788921923) [ 3724.531392] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 22:47:02 (1788922022) [ 3739.558225] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 22:47:16 (1788922036) [ 3753.010360] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 22:47:30 (1788922050) [ 3764.681714] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 22:47:42 (1788922062) [ 3780.846326] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 22:47:58 (1788922078) [ 3795.994762] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 22:48:13 (1788922093) [ 3808.171459] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 22:48:25 (1788922105) [ 3820.926064] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 22:48:38 (1788922118) [ 3835.064381] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 22:48:52 (1788922132) [ 3953.012856] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 22:50:50 (1788922250) [ 3992.547873] Lustre: lustre-OST0000-osc-ffff904651178000: disconnect after 24s idle [ 3992.560331] Lustre: Skipped 7 previous similar messages [ 4740.576471] Lustre: lustre-OST0001-osc-ffff904651f56000: disconnect after 21s idle [ 4740.596567] Lustre: Skipped 5 previous similar messages [ 5223.144719] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 23:12:00 (1788923520) [ 5237.928834] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 23:12:14 (1788923534) [ 5255.910227] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 23:12:32 (1788923552) [ 5272.284284] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 23:12:49 (1788923569) [ 5286.358562] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 23:13:03 (1788923583) [ 5300.202246] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 23:13:17 (1788923597) [ 5314.447399] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 23:13:32 (1788923612) [ 5328.278732] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 23:13:45 (1788923625) [ 5343.985829] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 23:14:01 (1788923641) [ 5358.086825] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 23:14:15 (1788923655) [ 5360.095438] Lustre: lustre-OST0000-osc-ffff904651178000: disconnect after 23s idle [ 5360.101675] Lustre: Skipped 7 previous similar messages [ 5509.995921] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 23:16:47 (1788923807) [ 5522.142990] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 23:16:59 (1788923819) [ 5533.908927] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 23:17:11 (1788923831) [ 5547.038465] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 23:17:24 (1788923844) [ 5559.614560] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 23:17:37 (1788923857) [ 5572.292427] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 23:17:49 (1788923869) [ 5585.541917] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 23:18:02 (1788923882) [ 5597.811288] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 23:18:15 (1788923895) [ 5609.328913] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 23:18:26 (1788923906) [ 5969.375322] Lustre: lustre-OST0001-osc-ffff904651178000: disconnect after 24s idle [ 5969.386499] Lustre: Skipped 7 previous similar messages [ 6619.615258] Lustre: lustre-OST0001-osc-ffff904651178000: disconnect after 20s idle [ 6619.632068] Lustre: Skipped 11 previous similar messages [ 6868.323690] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 23:39:25 (1788925165) [ 6880.536157] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 23:39:37 (1788925177) [ 6893.105650] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 23:39:50 (1788925190) [ 6905.597777] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 23:40:02 (1788925202) [ 6918.563772] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 23:40:15 (1788925215) [ 6934.124820] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 23:40:31 (1788925231) [ 6948.865738] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 23:40:45 (1788925245) [ 6963.923733] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 23:41:01 (1788925261) [ 6981.865630] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 23:41:18 (1788925278) [ 6995.878168] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 23:41:33 (1788925293) [ 7008.563591] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 23:41:45 (1788925305) [ 7022.468530] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 23:41:59 (1788925319) [ 7035.656162] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 23:42:12 (1788925332) [ 7048.750505] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 23:42:26 (1788925346) [ 7061.407819] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 23:42:38 (1788925358) [ 7074.345579] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 23:42:51 (1788925371) [ 7091.302958] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 23:43:08 (1788925388) [ 7091.708470] LustreError: 30715:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 7093.799162] LustreError: 30715:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 7103.338360] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 23:43:20 (1788925400) [ 7112.105869] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 23:43:29 (1788925409) [ 7112.427760] LustreError: 241217:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7116.495397] LustreError: 241217:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 7116.542249] LustreError: 241217:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7120.615192] LustreError: 241217:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 7120.672750] LustreError: 241223:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7124.736752] LustreError: 241223:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 7131.260544] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 23:43:49 (1788925429) [ 7143.753971] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 23:44:01 (1788925441) [ 7151.018891] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 23:44:08 (1788925448) [ 7159.327232] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 23:44:16 (1788925456) [ 7191.696427] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 23:44:49 (1788925489) [ 7204.535679] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 23:45:01 (1788925501) [ 7218.005881] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 23:45:15 (1788925515) [ 7236.295954] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 23:45:33 (1788925533) [ 7252.937872] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 23:45:49 (1788925549) [ 7271.663172] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 23:46:09 (1788925569) [ 7290.769310] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 23:46:28 (1788925588) [ 7315.867414] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 23:46:53 (1788925613) [ 7336.354406] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 23:47:13 (1788925633) [ 7433.774717] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 23:48:51 (1788925731) [ 7442.943237] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 7449.330825] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 23:49:06 (1788925746) [ 7456.505146] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 23:49:13 (1788925753) [ 7463.000481] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 23:49:20 (1788925760) [ 7469.413573] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 23:49:27 (1788925767) [ 7505.239890] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 23:50:02 (1788925802) [ 7565.711519] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 23:51:03 (1788925863) [ 7572.184273] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 23:51:09 (1788925869) [ 7578.835431] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 23:51:16 (1788925876) [ 7582.199400] LustreError: lustre-MDT0000-mdc-ffff904651f56000: operation ldlm_enqueue to node 192.168.203.126@tcp failed: rc = -35 [ 7589.710232] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 23:51:27 (1788925887) [ 7590.325441] LustreError: 2394:0:(osc_request.c:3139:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 7592.417247] LustreError: 2394:0:(osc_request.c:3139:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 7597.535334] Lustre: lustre-OST0000-osc-ffff904651f56000: disconnect after 20s idle [ 7597.543881] Lustre: Skipped 14 previous similar messages [ 7602.020844] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 23:51:39 (1788925899) [ 7786.186642] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 23:54:43 (1788926083) [ 7796.728826] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 23:54:54 (1788926094) [ 7810.063053] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 23:55:07 (1788926107) [ 7825.479305] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 23:55:22 (1788926122) [ 7840.047369] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 23:55:37 (1788926137) [ 7861.406287] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 23:55:58 (1788926158) [ 7883.698451] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 23:56:20 (1788926180) [ 7893.862516] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 23:56:31 (1788926191) [ 7904.897552] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 23:56:42 (1788926202) [ 7927.546438] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 23:57:04 (1788926224) [ 7983.822657] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 23:58:01 (1788926281) [ 8121.736390] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 00:00:19 (1788926419) [ 8217.055264] Lustre: lustre-OST0001-osc-ffff904651178000: disconnect after 23s idle [ 8217.073789] Lustre: Skipped 12 previous similar messages [ 8259.552402] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 00:02:36 (1788926556) [ 8619.721611] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 00:08:37 (1788926917) [ 8658.883823] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 00:09:16 (1788926956) [ 8703.518895] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 00:10:00 (1788927000) [ 8740.047837] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 00:10:37 (1788927037) [ 8817.096710] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 00:11:54 (1788927114) [ 8841.701112] Lustre: lustre-OST0001-osc-ffff904651178000: disconnect after 22s idle [ 8841.707883] Lustre: Skipped 15 previous similar messages [ 8883.133222] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 00:13:00 (1788927180) [ 9002.839941] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 00:15:00 (1788927300) [ 9007.132848] Lustre: Unmounted lustre-client [ 9010.900782] Lustre: Unmounted lustre-client [ 9116.123536] Lustre: Mounted lustre-client - version 2.17.58_2_g8df9471 [ 9118.683315] Lustre: Mounted lustre-client - version 2.17.58_2_g8df9471 [ 9121.613491] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9214.778290] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 00:18:32 (1788927512) [ 9234.126871] Lustre: DEBUG MARKER: == sanityn test 77kj: Verify nodemap support for NRS TBF rule ========================================================== 00:18:51 (1788927531) [ 9376.004144] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 00:21:13 (1788927673) [ 9385.940192] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 00:21:23 (1788927683) [ 9445.272477] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 00:22:22 (1788927742) [ 9471.972580] Lustre: lustre-OST0001-osc-ffff9046431be000: disconnect after 23s idle [ 9471.980655] Lustre: Skipped 15 previous similar messages [ 9519.798499] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 00:23:37 (1788927817) [ 9532.872929] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 00:23:50 (1788927830) [ 9656.649718] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 00:25:53 (1788927953) [ 9695.707466] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 00:26:32 (1788927992) [ 9748.582678] Lustre: DEBUG MARKER: == sanityn test 77s: Check TBF LRU shrinker ============== 00:27:25 (1788928045) [ 9815.401218] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 00:28:32 (1788928112) [ 9825.085632] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 00:28:42 (1788928122) [ 9843.749985] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 00:29:01 (1788928141) [ 9854.979073] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 00:29:12 (1788928152) [ 9856.622329] LustreError: 308602:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x564:0x0]: rc = -5 [ 9856.631772] LustreError: 308602:0:(llite_lib.c:3893:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9857.366921] LustreError: 308613:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x2d:0x0]: rc = -5 [ 9857.375096] LustreError: 308613:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 1 previous similar message [ 9857.407608] LustreError: 308613:0:(llite_lib.c:3893:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9857.426835] LustreError: 308613:0:(llite_lib.c:3893:ll_prep_inode()) Skipped 1 previous similar message [ 9858.793246] LustreError: 308633:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x56a:0x0]: rc = -5 [ 9858.810707] LustreError: 308633:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 3 previous similar messages [ 9858.818054] LustreError: 308633:0:(llite_lib.c:3893:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9858.829571] LustreError: 308633:0:(llite_lib.c:3893:ll_prep_inode()) Skipped 3 previous similar messages [ 9861.243010] LustreError: 308674:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x576:0x0]: rc = -5 [ 9861.254749] LustreError: 308674:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 7 previous similar messages [ 9861.261230] LustreError: 308674:0:(llite_lib.c:3893:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9861.268057] LustreError: 308674:0:(llite_lib.c:3893:ll_prep_inode()) Skipped 7 previous similar messages [ 9865.822663] LustreError: 308754:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x4b:0x0]: rc = -5 [ 9865.834499] LustreError: 308754:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 15 previous similar messages [ 9865.852089] LustreError: 308754:0:(llite_lib.c:3893:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9865.864431] LustreError: 308754:0:(llite_lib.c:3893:ll_prep_inode()) Skipped 15 previous similar messages [ 9973.753740] Lustre: lustre-MDT0001-mdc-ffff9046431be000: Connection to lustre-MDT0001 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9973.772352] LustreError: 308442:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x5e:0x0]: rc = -5 [ 9973.787121] LustreError: 308442:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 10 previous similar messages [ 9973.792100] LustreError: lustre-MDT0001-mdc-ffff9046431be000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 9973.792486] LustreError: 308442:0:(llite_lib.c:3893:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9973.792503] LustreError: 308442:0:(llite_lib.c:3893:ll_prep_inode()) Skipped 10 previous similar messages [ 9973.808707] LustreError: 308805:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9046431be000: inode [0x240000bd0:0x5c:0x0] mdc close failed: rc = -108 [ 9973.814274] Lustre: lustre-MDT0001-mdc-ffff9046431be000: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [ 9991.608300] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 00:31:28 (1788928288) [10000.170583] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 00:31:37 (1788928297) [10228.888969] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 00:35:26 (1788928526) [10230.525407] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [10232.277195] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 00:35:29 (1788928529) [10452.243910] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 00:39:09 (1788928749) [10459.568761] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 00:39:17 (1788928757) [10475.490114] Lustre: lustre-OST0000-osc-ffff90464785f000: disconnect after 23s idle [10475.495663] Lustre: Skipped 7 previous similar messages [10588.853152] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 00:41:25 (1788928885) [10601.715312] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 00:41:39 (1788928899) [10612.607308] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 00:41:49 (1788928909) [10801.013299] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 00:44:58 (1788929098) [10988.529298] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 00:48:05 (1788929285) [10995.177831] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 00:48:12 (1788929292) [11013.921552] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 00:48:30 (1788929310) [11014.250301] Lustre: DEBUG MARKER: write [11014.325666] LustreError: 292135:0:(ldlm_request.c:1533:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [11016.364511] Lustre: DEBUG MARKER: kill 338823 [11016.385199] LustreError: 338823:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [11019.337143] LustreError: 292135:0:(ldlm_request.c:1533:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [11022.447813] LustreError: 338823:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [11029.964708] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 00:48:47 (1788929327) [11033.142582] LustreError: 339438:0:(rw.c:1865:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [11043.184582] LustreError: 339438:0:(rw.c:1865:ll_readpage()) cfs_fail_timeout id 1422 awake [11051.489775] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 00:49:08 (1788929348) [11052.129445] LustreError: 340026:0:(rw.c:2112:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [11054.215166] LustreError: 340026:0:(rw.c:2112:ll_readpage()) cfs_fail_timeout interrupted [11066.876922] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 00:49:24 (1788929364) [11068.926995] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [11070.406892] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 00:49:28 (1788929368) [11077.872910] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 00:49:35 (1788929375) [11084.822592] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 00:49:42 (1788929382) [11092.537141] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 00:49:49 (1788929389) [11101.569500] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 00:49:58 (1788929398) [11110.435624] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 00:50:07 (1788929407) [11120.508339] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 00:50:17 (1788929417) [11129.611364] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 00:50:26 (1788929426) [11139.151046] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 00:50:36 (1788929436) [11141.247204] Lustre: *** cfs_fail_loc=415, val=0*** [11153.786408] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 00:50:51 (1788929451) [11183.694349] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 00:51:21 (1788929481) [11184.159368] LustreError: 289140:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [11184.183143] LustreError: 289140:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [11189.176932] LustreError: 289139:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [11189.209501] LustreError: 289139:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 1 previous similar message [11199.351175] LustreError: 289139:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [11199.363961] LustreError: 289139:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 5 previous similar messages [11212.816157] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 00:51:49 (1788929509) [11220.841404] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 00:51:58 (1788929518) [11228.671616] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 00:52:06 (1788929526) [11236.280740] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 00:52:13 (1788929533) [11246.474233] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 00:52:23 (1788929543) [11260.969697] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 00:52:38 (1788929558) [11261.638539] LustreError: 350775:0:(osc_request.c:2990:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [11261.643993] LustreError: 350775:0:(osc_request.c:2990:osc_build_rpc()) Skipped 6 previous similar messages [11265.714358] LustreError: 350775:0:(osc_request.c:2990:osc_build_rpc()) cfs_fail_timeout id 414 awake [11265.717829] LustreError: 350775:0:(osc_request.c:2990:osc_build_rpc()) Skipped 2 previous similar messages [11273.009052] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 00:52:50 (1788929570) [11278.289829] Lustre: Unmounted lustre-client [11281.474681] Lustre: Unmounted lustre-client [11283.109156] Lustre: DEBUG MARKER: Iteration 0 [11283.555451] LustreError: 351669:0:(llite_lib.c:1508:ll_fill_super()) cfs_race id 1417 sleeping [11283.557766] LustreError: 351670:0:(llite_lib.c:1508:ll_fill_super()) cfs_fail_race id 1417 waking [11283.568372] LustreError: 351669:0:(llite_lib.c:1508:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [11283.831811] Lustre: Mounted lustre-client - version 2.17.58_2_g8df9471 [11285.637132] Lustre: Unmounted lustre-client [11288.710520] Key type lgssc unregistered [11289.042407] LNet: 352013:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11289.050867] LNetError: 352013:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11289.079752] LNet: Removed LNI 192.168.203.26@tcp [11290.172163] Key type .llcrypt unregistered [11290.178247] Key type ._llcrypt unregistered [11291.189505] Key type ._llcrypt registered [11291.218637] Key type .llcrypt registered [11291.867454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11291.898905] alg: No test for adler32 (adler32-zlib) [11293.498384] Lustre: Lustre: Build Version: 2.17.58_2_g8df9471 [11294.605098] LNet: Added LNI 192.168.203.26@tcp [8/256/0/180] [11296.455202] Key type lgssc registered [11298.380760] Lustre: Echo OBD driver; http://www.lustre.org/ [11314.145693] Lustre: Mounted lustre-client - version 2.17.58_2_g8df9471 [11322.365923] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 00:53:39 (1788929619) [11339.231494] Lustre: 353355:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788929622/real 1788929622] req@ffff904678dbd880 x1875828636920832/t0(0) o36->lustre-MDT0000-mdc-ffff904650bec800@192.168.203.126@tcp:12/10 lens 496/440 e 0 to 1 dl 1788929638 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11339.297491] Lustre: lustre-MDT0000-mdc-ffff904650bec800: Connection to lustre-MDT0000 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [11339.332029] Lustre: lustre-MDT0000-mdc-ffff904650bec800: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [11355.615131] Lustre: 353355:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788929638/real 1788929638] req@ffff904678dbd880 x1875828636920832/t0(0) o36->lustre-MDT0000-mdc-ffff904650bec800@192.168.203.126@tcp:12/10 lens 496/440 e 0 to 1 dl 1788929654 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11355.655779] Lustre: lustre-MDT0000-mdc-ffff904650bec800: Connection to lustre-MDT0000 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [11355.705419] Lustre: lustre-MDT0000-mdc-ffff904650bec800: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [11372.001756] Lustre: 353355:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788929654/real 1788929654] req@ffff904678dbd880 x1875828636920832/t0(0) o36->lustre-MDT0000-mdc-ffff904650bec800@192.168.203.126@tcp:12/10 lens 496/440 e 0 to 1 dl 1788929670 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11372.037090] Lustre: lustre-MDT0000-mdc-ffff904650bec800: Connection to lustre-MDT0000 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [11372.121648] Lustre: lustre-MDT0000-mdc-ffff904650bec800: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [11387.359840] Lustre: 353355:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788929670/real 1788929670] req@ffff904678dbd880 x1875828636920832/t0(0) o36->lustre-MDT0000-mdc-ffff904650bec800@192.168.203.126@tcp:12/10 lens 496/440 e 0 to 1 dl 1788929686 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11387.432461] Lustre: lustre-MDT0000-mdc-ffff904650bec800: Connection to lustre-MDT0000 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [11387.485263] Lustre: lustre-MDT0000-mdc-ffff904650bec800: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [11403.743158] Lustre: 353355:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788929686/real 1788929686] req@ffff904678dbd880 x1875828636920832/t0(0) o36->lustre-MDT0000-mdc-ffff904650bec800@192.168.203.126@tcp:12/10 lens 496/440 e 0 to 1 dl 1788929702 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11403.830538] Lustre: lustre-MDT0000-mdc-ffff904650bec800: Connection to lustre-MDT0000 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [11403.919233] Lustre: lustre-MDT0000-mdc-ffff904650bec800: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [11406.271125] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 00:55:02 (1788929702) [11420.105364] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 00:55:17 (1788929717) [11434.094531] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 00:55:30 (1788929730) [11442.734798] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 00:55:39 (1788929739) [11471.046299] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 00:56:08 (1788929768) [11510.937780] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 00:56:48 (1788929808) [11521.730837] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 00:56:59 (1788929819) [11522.125230] LustreError: 358147:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [11524.144723] LustreError: 358147:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [11533.710676] Lustre: DEBUG MARKER: == sanityn test 122: directory size is consistent across mounts ========================================================== 00:57:10 (1788929830) [11552.165226] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 00:57:29 (1788929849) [11574.240112] Lustre: 352202:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788929857/real 1788929857] req@ffff904667b73100 x1875828637990528/t0(0) o4->lustre-OST0000-osc-ffff904650bec800@192.168.203.126@tcp:6/4 lens 4584/448 e 0 to 1 dl 1788929873 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [11574.247889] Lustre: lustre-OST0000-osc-ffff904650bec800: Connection to lustre-OST0000 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [11574.305816] Lustre: 352202:0:(client.c:2504:ptlrpc_expire_one_request()) Skipped 1 previous similar message [11574.427732] Lustre: lustre-OST0000-osc-ffff904650bec800: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [11590.623157] Lustre: 352202:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788929873/real 1788929873] req@ffff904667b71500 x1875828637991296/t0(0) o4->lustre-OST0000-osc-ffff904650bec800@192.168.203.126@tcp:6/4 lens 4584/448 e 0 to 1 dl 1788929889 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [11590.624338] Lustre: lustre-OST0000-osc-ffff904650bec800: Connection to lustre-OST0000 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [11590.689629] Lustre: 352202:0:(client.c:2504:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [11590.751046] Lustre: lustre-OST0000-osc-ffff904650bec800: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [11638.751184] Lustre: 352203:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788929921/real 1788929921] req@ffff90466de0d180 x1875828637990656/t0(0) o4->lustre-OST0000-osc-ffff904650bec800@192.168.203.126@tcp:6/4 lens 4584/448 e 0 to 1 dl 1788929937 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [11638.756135] Lustre: lustre-OST0000-osc-ffff904650bec800: Connection to lustre-OST0000 (at 192.168.203.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [11638.810233] Lustre: 352203:0:(client.c:2504:ptlrpc_expire_one_request()) Skipped 11 previous similar messages [11638.880501] Lustre: Skipped 2 previous similar messages [11638.913658] Lustre: lustre-OST0000-osc-ffff904650bec800: Connection restored to 192.168.203.126@tcp (at 192.168.203.126@tcp) [11638.925429] Lustre: Skipped 2 previous similar messages [11655.441436] Lustre: DEBUG MARKER: oleg326-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff904650bec800.ost_server_uuid 50 [11657.253517] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff904650bec800.ost_server_uuid in FULL state after 0 sec [11659.354995] Lustre: DEBUG MARKER: cleanup: ====================================================== [11661.149433] Lustre: DEBUG MARKER: == sanityn test complete, duration 11408 sec ============= 00:59:18 (1788929958) [11662.225408] Lustre: DEBUG MARKER: === sanityn: start cleanup 00:59:20 (1788929960) === [11970.124239] Lustre: Unmounted lustre-client [11975.185961] Lustre: DEBUG MARKER: === sanityn: finish cleanup 01:04:32 (1788930272) === [11978.080073] Lustre: Unmounted lustre-client [12037.501778] Key type lgssc unregistered [12037.938118] LNet: 362232:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12037.966026] LNetError: 362232:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12038.016694] LNet: Removed LNI 192.168.203.26@tcp [12039.415277] Key type .llcrypt unregistered [12039.421403] Key type ._llcrypt unregistered