[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 542527651 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524588K 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.001017] APIC: Switch to symmetric I/O mode setup [ 0.003297] x2apic enabled [ 0.004000] Switched APIC routing to physical x2apic. [ 0.004018] kvm-guest: setup PV IPIs [ 0.007456] ..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.008020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009011] pid_max: default: 32768 minimum: 301 [ 0.010144] LSM: Security Framework initializing [ 0.011052] Yama: becoming mindful. [ 0.012047] SELinux: Initializing. [ 0.013088] *** VALIDATE selinux *** [ 0.020496] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026319] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027156] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028113] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030103] *** VALIDATE tmpfs *** [ 0.031432] *** VALIDATE proc *** [ 0.033231] *** VALIDATE cgroup *** [ 0.034007] *** VALIDATE cgroup2 *** [ 0.035546] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037114] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039026] Spectre V2 : User space: Vulnerable [ 0.040006] Speculative Store Bypass: Vulnerable [ 0.043379] debug: unmapping init [mem 0xffffffff87c59000-0xffffffff87c60fff] [ 0.045270] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047110] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048027] ... version: 2 [ 0.049012] ... bit width: 48 [ 0.050019] ... generic registers: 4 [ 0.051019] ... value mask: 0000ffffffffffff [ 0.052018] ... max period: 00007fffffffffff [ 0.053013] ... fixed-purpose events: 3 [ 0.054015] ... event mask: 000000070000000f [ 0.055334] rcu: Hierarchical SRCU implementation. [ 0.057561] smp: Bringing up secondary CPUs ... [ 0.058675] x86: Booting SMP configuration: [ 0.059043] .... node #0, CPUs: #1 #2 #3 [ 0.069089] smp: Brought up 1 node, 4 CPUs [ 0.071015] smpboot: Max logical packages: 1 [ 0.072013] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.118425] node 0 deferred pages initialised in 44ms [ 0.122155] devtmpfs: initialized [ 0.123214] x86/mm: Memory block size: 128MB [ 0.126235] gcov: version magic: 0x41383552 [ 0.128093] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.129074] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.130368] pinctrl core: initialized pinctrl subsystem [ 0.131339] [ 0.131882] ************************************************************* [ 0.132008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.133014] ** ** [ 0.134013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.135013] ** ** [ 0.136016] ** This means that this kernel is built to expose internal ** [ 0.137015] ** IOMMU data structures, which may compromise security on ** [ 0.138010] ** your system. ** [ 0.139010] ** ** [ 0.140012] ** If you see this message and you are not debugging the ** [ 0.141012] ** kernel, report this immediately to your vendor! ** [ 0.142010] ** ** [ 0.143011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.144012] ************************************************************* [ 0.145807] NET: Registered protocol family 16 [ 0.146623] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.147055] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.148059] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.149905] cpuidle: using governor menu [ 0.152032] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.155503] PCI: Using configuration type 1 for base access [ 0.158122] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.167202] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.168059] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.169249] cryptd: max_cpu_qlen set to 1000 [ 0.170779] ACPI: Added _OSI(Module Device) [ 0.171062] ACPI: Added _OSI(Processor Device) [ 0.172007] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.173008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.177844] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.181708] ACPI: Interpreter enabled [ 0.182113] ACPI: PM: (supports S0 S3 S4 S5) [ 0.183313] ACPI: Using IOAPIC for interrupt routing [ 0.184430] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.186157] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.202175] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.203051] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.204021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.205154] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.207263] acpiphp: Slot [2] registered [ 0.208118] acpiphp: Slot [5] registered [ 0.210212] acpiphp: Slot [6] registered [ 0.211091] acpiphp: Slot [3] registered [ 0.212108] acpiphp: Slot [4] registered [ 0.213094] acpiphp: Slot [7] registered [ 0.214129] acpiphp: Slot [8] registered [ 0.215135] acpiphp: Slot [9] registered [ 0.216095] acpiphp: Slot [10] registered [ 0.217100] acpiphp: Slot [11] registered [ 0.218126] acpiphp: Slot [12] registered [ 0.219171] acpiphp: Slot [13] registered [ 0.220115] acpiphp: Slot [14] registered [ 0.221132] acpiphp: Slot [15] registered [ 0.222149] acpiphp: Slot [16] registered [ 0.223118] acpiphp: Slot [17] registered [ 0.224103] acpiphp: Slot [18] registered [ 0.225216] acpiphp: Slot [19] registered [ 0.226119] acpiphp: Slot [20] registered [ 0.227230] acpiphp: Slot [21] registered [ 0.228107] acpiphp: Slot [22] registered [ 0.229154] acpiphp: Slot [23] registered [ 0.230092] acpiphp: Slot [24] registered [ 0.231095] acpiphp: Slot [25] registered [ 0.232136] acpiphp: Slot [26] registered [ 0.233187] acpiphp: Slot [27] registered [ 0.234105] acpiphp: Slot [28] registered [ 0.235208] acpiphp: Slot [29] registered [ 0.236110] acpiphp: Slot [30] registered [ 0.237139] acpiphp: Slot [31] registered [ 0.238065] PCI host bridge to bus 0000:00 [ 0.239017] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.240043] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.242027] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.246025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.248021] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.252043] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.254319] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.258527] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.262543] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.271014] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.306049] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.310019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.318018] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.323019] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.341702] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.345203] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.348045] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.351901] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.355982] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.363944] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.367844] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.373155] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.378014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.383014] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.415027] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.432281] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.442019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.448014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.454000] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.458000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.462469] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.465519] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.468569] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.471243] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.477032] iommu: Default domain type: Passthrough [ 0.482680] SCSI subsystem initialized [ 0.484251] ACPI: bus type USB registered [ 0.486624] usbcore: registered new interface driver usbfs [ 0.494366] usbcore: registered new interface driver hub [ 0.503139] usbcore: registered new device driver usb [ 0.508610] pps_core: LinuxPPS API ver. 1 registered [ 0.514042] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.522095] PTP clock support registered [ 0.527190] EDAC MC: Ver: 3.0.0 [ 0.531267] PCI: Using ACPI for IRQ routing [ 0.535849] NetLabel: Initializing [ 0.539030] NetLabel: domain hash size = 128 [ 0.540029] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.544266] NetLabel: unlabeled traffic allowed by default [ 0.548041] vgaarb: loaded [ 0.550478] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.553015] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.560674] clocksource: Switched to clocksource kvm-clock [ 0.763705] VFS: Disk quotas dquot_6.6.0 [ 0.765301] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.768730] *** VALIDATE ramfs *** [ 0.770385] *** VALIDATE hugetlbfs *** [ 0.773859] pnp: PnP ACPI init [ 0.778278] pnp: PnP ACPI: found 6 devices [ 0.800292] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.803824] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.805970] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.808349] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.811439] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.814537] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.817576] NET: Registered protocol family 2 [ 0.820566] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.825997] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.830831] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.837416] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.842517] TCP: Hash tables configured (established 65536 bind 65536) [ 0.846509] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.850641] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.854125] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.857242] NET: Registered protocol family 1 [ 0.859704] RPC: Registered named UNIX socket transport module. [ 0.861346] RPC: Registered udp transport module. [ 0.863051] RPC: Registered tcp transport module. [ 0.865307] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.867969] NET: Registered protocol family 44 [ 0.869697] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.872525] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.875045] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.877818] PCI: CLS 0 bytes, default 64 [ 0.879540] Unpacking initramfs... [ 2.922678] debug: unmapping init [mem 0xffff92867cc64000-0xffff92867ffcffff] [ 2.929391] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.931921] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.934463] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 4.340893] Initialise system trusted keyrings [ 4.342819] Key type blacklist registered [ 4.346471] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 4.359476] zbud: loaded [ 4.362920] *** VALIDATE nfs *** [ 4.366141] *** VALIDATE nfs4 *** [ 4.368946] pstore: using deflate compression [ 4.378452] Platform Keyring initialized [ 4.496731] NET: Registered protocol family 38 [ 4.498548] Key type asymmetric registered [ 4.499932] Asymmetric key parser 'x509' registered [ 4.501859] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.504958] io scheduler mq-deadline registered [ 4.506554] io scheduler kyber registered [ 4.508316] io scheduler bfq registered [ 4.509855] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.512800] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.515347] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.518491] ACPI: Power Button [PWRF] [ 4.524777] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.531611] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.556643] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.588853] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.630633] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.640520] Non-volatile memory driver v1.3 [ 4.644777] Linux agpgart interface v0.103 [ 4.748267] virtio_blk virtio1: [vda] 68000 512-byte logical blocks (34.8 MB/33.2 MiB) [ 4.757469] vda: detected capacity change from 0 to 34816000 [ 4.796771] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.799234] vdb: detected capacity change from 0 to 1073741824 [ 4.809526] libphy: Fixed MDIO Bus: probed [ 4.818442] usbcore: registered new interface driver usbserial_generic [ 4.820542] usbserial: USB Serial support registered for generic [ 4.822574] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.826649] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.828218] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.830324] mousedev: PS/2 mouse device common for all mice [ 4.832642] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.838953] rtc_cmos 00:05: RTC can wake from S4 [ 4.842323] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.846905] rtc_cmos 00:05: registered as rtc0 [ 4.851977] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.862607] intel_pstate: CPU model not supported [ 4.868103] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.877356] hid: raw HID events driver (C) Jiri Kosina [ 4.879836] usbcore: registered new interface driver usbhid [ 4.883974] usbhid: USB HID core driver [ 4.887096] drop_monitor: Initializing network drop monitor service [ 4.890616] Initializing XFRM netlink socket [ 4.894448] NET: Registered protocol family 10 [ 4.900986] Segment Routing with IPv6 [ 4.904237] NET: Registered protocol family 17 [ 4.908326] mpls_gso: MPLS GSO support [ 4.918503] RAS: Correctable Errors collector initialized. [ 4.922793] AVX version of gcm_enc/dec engaged. [ 4.926807] AES CTR mode by8 optimization enabled [ 5.213753] sched_clock: Marking stable (5213712013, 0)->(6542528233, -1328816220) [ 5.216552] registered taskstats version 1 [ 5.218780] Loading compiled-in X.509 certificates [ 5.221306] zswap: loaded using pool lzo/zbud [ 5.255887] Key type big_key registered [ 5.284668] Key type encrypted registered [ 5.286693] ima: No TPM chip found, activating TPM-bypass! [ 5.289491] ima: Allocated hash algorithm: sha1 [ 5.291944] ima: No architecture policies found [ 5.293910] evm: Initialising EVM extended attributes: [ 5.296759] evm: security.selinux [ 5.298195] evm: security.ima [ 5.299616] evm: security.capability [ 5.301415] evm: HMAC attrs: 0x1 [ 5.303994] rtc_cmos 00:05: setting system clock to 2026-02-24 21:28:46 UTC (1771968526) [ 5.315771] debug: unmapping init [mem 0xffffffff88c03000-0xffffffff88dfffff] [ 5.320703] debug: unmapping init [mem 0xffffffff87982000-0xffffffff87c58fff] [ 5.336438] Write protecting the kernel read-only data: 28672k [ 5.341696] debug: unmapping init [mem 0xffffffff86003000-0xffffffff861fffff] [ 5.346388] debug: unmapping init [mem 0xffffffff86914000-0xffffffff869fffff] [ 5.430969] 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) [ 5.447680] systemd[1]: Detected virtualization kvm. [ 5.453467] systemd[1]: Detected architecture x86-64. [ 5.459364] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.501437] systemd[1]: No hostname configured. [ 5.503500] systemd[1]: Set hostname to . [ 5.505527] random: systemd: uninitialized urandom read (16 bytes read) [ 5.508638] systemd[1]: Initializing machine ID from random generator. [ 5.818893] random: ln: uninitialized urandom read (6 bytes read) [ 6.612700] random: systemd: uninitialized urandom read (16 bytes read) [ 6.624350] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 6.668528] random: systemd: uninitialized urandom read (16 bytes read) [ 6.679945] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 6.698551] random: systemd: uninitialized urandom read (16 bytes read) [ 6.707854] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... Starting Journal Service... [ OK ] Reached target Local File Systems. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. Starting Create Volatile Files and Directories... [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Slices. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Swap. Starting Apply Kernel Variables... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 8.308510] device-mapper: uevent: version 1.0.3 [ 8.310783] 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. [ 10.503280] virtio_net virtio0 ens2: renamed from eth0 [ 10.973252] scsi host0: ata_piix [ 11.009193] scsi host1: ata_piix [ 11.010266] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 11.019136] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 14.207213] random: fast init done [ 15.818594] random: crng init done [ 15.819817] random: 5 urandom warning(s) missed due to ratelimiting [ 18.127426] dracut-initqueue[584]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 19.709476] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 22.111494] printk: systemd: 25 output lines suppressed due to ratelimiting [ 22.843839] SELinux: Disabled at runtime. [ 22.970399] 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) [ 23.005472] systemd[1]: Detected virtualization kvm. [ 23.007298] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 24.978748] systemd[1]: initrd-switch-root.service: Succeeded. [ 24.987812] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 25.003966] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 25.012210] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 25.020366] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 25.030848] systemd[1]: Starting Journal Service... Starting Journal Service... [ 25.051497] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... [ OK ] Created slice User and Session Slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Switch Root. Mounting Huge Pages File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Created slice system-serial\x2dgetty.slice. [ 25.360071] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target Slices. Starting Apply Kernel Variables... [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Mounting POSIX Message Queue File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Mounting Kernel Debug File System... [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug 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. [ 26.561578] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 27.671374] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 27.762387] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 28.390161] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 28.558265] EDAC sbridge: Ver: 1.1.2 [ 31.609755] Key type dns_resolver registered [ 32.227017] hrtimer: interrupt took 8008176 ns [* ] A start job is running for Configur…-only root support (7s / no limit)[ 32.675795] NFS: Registering the id_resolver key type [ 32.678958] Key type id_resolver registered [ 32.680913] 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 Create Volatile Files and Directories. [ OK ] Started Load/Save Random Seed. 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 dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ 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 Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Started Login Service. Starting Hostname Service... Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg352-client login: [ 69.138653] libcfs: loading out-of-tree module taints kernel. [ 69.175489] alg: No test for adler32 (adler32-zlib) [ 69.928274] Key type ._llcrypt registered [ 69.929341] Key type .llcrypt registered [ 70.059159] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 70.288830] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 70.568333] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 70.570587] LNet: Accept secure, port 988 [ 72.191119] Key type lgssc registered [ 72.909089] Lustre: Echo OBD driver; http://www.lustre.org/ [ 152.591508] Lustre: Mounted lustre-client [ 155.284947] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 169.230339] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing check_logdir /tmp/testlogs/ [ 171.821988] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing yml_node [ 173.970249] Lustre: DEBUG MARKER: Client: 2.15.8.1 [ 175.195557] Lustre: DEBUG MARKER: MDS: 2.15.8.1 [ 176.449124] Lustre: DEBUG MARKER: OSS: 2.15.8.1 [ 177.200245] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Tue Feb 24 16:31:37 EST 2026 [ 178.143138] Lustre: lustre-OST0000-osc-ffff9286d0d6b000: disconnect after 24s idle [ 180.758881] Lustre: DEBUG MARKER: excepting tests: 27 28 [ 181.487639] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 181.818755] Lustre: Mounted lustre-client [ 184.501067] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing check_config_client /mnt/lustre [ 193.589088] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 199.281119] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 16:31:59 (1771968719) [ 202.197906] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 16:32:02 (1771968722) [ 205.343794] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 16:32:05 (1771968725) [ 208.676544] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 16:32:09 (1771968729) [ 212.031408] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 16:32:12 (1771968732) [ 215.339481] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 16:32:15 (1771968735) [ 218.591386] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 16:32:19 (1771968739) [ 221.865402] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 16:32:22 (1771968742) [ 224.708232] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 16:32:25 (1771968745) [ 227.253249] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 16:32:27 (1771968747) [ 230.782473] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 16:32:31 (1771968751) [ 233.307147] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 16:32:33 (1771968753) [ 235.791982] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 16:32:36 (1771968756) [ 238.334380] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 16:32:38 (1771968758) [ 240.906863] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 16:32:41 (1771968761) [ 243.897956] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 16:32:44 (1771968764) [ 246.680338] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 16:32:47 (1771968767) [ 249.626161] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 16:32:50 (1771968770) [ 252.133633] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 16:32:52 (1771968772) [ 255.085510] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 16:32:55 (1771968775) [ 255.363379] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279508 file: /mnt/lustre/lockdir/lockfile=144115205289279507 [ 387.794560] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 16:35:08 (1771968908) [ 390.402276] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 16:35:11 (1771968911) [ 392.642261] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 16:35:13 (1771968913) [ 395.107275] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 16:35:15 (1771968915) [ 397.876422] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 16:35:18 (1771968918) [ 400.367054] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 16:35:21 (1771968921) [ 401.064148] Lustre: DEBUG MARKER: chmod [ 403.969178] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 16:35:24 (1771968924) [ 407.877327] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7207500kB free gt MAXFREE 800000kB, increase 800000 (or reduce test fs size) to proceed [ 413.009537] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 16:35:33 (1771968933) [ 489.880271] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 16:36:50 (1771969010) [ 520.625645] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 16:37:21 (1771969041) [ 572.434971] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 16:38:13 (1771969093) [ 584.146179] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 16:38:24 (1771969104) [ 586.955072] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 16:38:27 (1771969107) [ 587.642754] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 587.687805] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 587.737496] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 587.775366] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 587.804058] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 587.833881] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 587.862248] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 587.885692] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 587.915290] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 587.943928] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 587.975734] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.006252] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.033784] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.063810] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.093419] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.121248] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.152734] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.180920] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.212906] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.248276] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.270764] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.296919] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.321365] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.359145] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.396717] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.444251] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.479253] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.506627] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.533471] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.564644] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.594341] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.630340] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.657067] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.688255] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.716414] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.745160] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.777838] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.807676] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.841243] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.874597] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.906516] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.934236] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 588.971819] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.000430] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.024755] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.049960] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.075575] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.103489] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.131028] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.155049] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.181214] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.210627] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.234387] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.259823] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.282458] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.306321] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.330140] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.355732] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.377879] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.399530] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.425610] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.452471] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.479722] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.501457] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.524696] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.549934] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.576383] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.599639] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.623445] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.646366] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.670895] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.692629] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.716048] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.743643] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.766599] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.788519] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.811157] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.834735] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.860340] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.886754] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.911317] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.932691] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.958647] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 589.981613] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.009395] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.034509] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.057120] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.078817] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.101429] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.126313] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.151872] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.175325] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.203111] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.227796] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.256624] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.280176] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.304328] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.329757] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.354429] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.378561] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.401424] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.427477] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.450152] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.477134] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.504416] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.530186] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.557733] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.586153] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.618556] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.648781] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.679448] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.707862] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.732647] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.756410] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.783537] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.810284] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.837301] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.860793] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.883330] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.909301] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.932686] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.958118] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 590.983472] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.006932] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.030990] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.055270] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.078791] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.101308] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.124374] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.147073] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.176377] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.203827] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.228733] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.257283] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.280714] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.305360] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.329556] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.351660] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.375874] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.402616] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.434310] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.459318] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.488607] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.510288] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.534345] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.559802] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.586221] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.610031] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.636507] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.663063] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.690307] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.712734] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.739359] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.765398] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.796594] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.819935] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.843374] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.865067] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.888662] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.912193] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.936624] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.958716] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 591.981042] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.007752] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.035422] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.063123] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.099959] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.134790] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.161645] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.184373] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.207048] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.235595] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.263192] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.289614] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.313793] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.339146] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.367604] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.393921] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.420755] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.448889] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.473423] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.495893] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.518442] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.542067] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.566380] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.593386] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.626553] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.654060] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.682704] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.705877] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.732228] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.755854] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.777179] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.802568] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.832616] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.859963] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.889979] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.914284] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.939360] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.963609] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 592.997576] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.020532] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.041023] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.062782] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.086389] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.112773] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.142893] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.164152] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.186918] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.217064] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.240066] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.267637] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.290777] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.315059] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.338802] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.362915] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.386952] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.417705] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.444734] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.467074] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.490809] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.517975] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.545184] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.573096] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.608992] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.633909] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.659591] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.697854] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.727512] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.750177] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.777769] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.804347] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.828845] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.850070] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.872727] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.896917] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.922388] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.950253] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 593.981268] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.007027] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.031981] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.054298] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.079523] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.104494] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.135172] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.158566] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.192333] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.213220] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.235615] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.257014] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.281815] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.307322] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.338983] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.361166] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.383601] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.405154] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.426520] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.450349] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.473277] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.498826] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.523324] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.546746] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.566953] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.589036] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.611281] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.633522] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.660760] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.688776] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.712883] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.737144] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.760576] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.785953] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.809450] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.833649] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.859459] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.882151] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.903682] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.926569] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.948160] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.970556] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 594.994955] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.019258] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.039453] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.061094] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.083301] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.104244] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.125864] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.146157] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.167036] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.189899] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.212708] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.235722] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.257066] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.279639] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.306475] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.329707] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.353634] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.378280] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.400868] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.426680] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.451900] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.477941] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.509144] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.542038] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.567879] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.591635] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.614808] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.637431] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.666757] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.693936] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.722142] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.748515] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.772809] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.794652] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.818166] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.840416] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.864368] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.892388] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.918900] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.946220] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.968968] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 595.994879] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.016029] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.045708] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.071920] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.095480] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.117066] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.141470] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.166569] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.192524] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.217946] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.241635] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.264446] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.290314] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.316281] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.343449] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.367396] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.394600] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.424507] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.448087] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.473276] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.497614] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.521436] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.550264] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.572248] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.594544] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.616750] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.639503] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.663240] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.687145] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.714763] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.740574] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.770633] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.798671] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.822723] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.846724] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.870226] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.893721] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.918380] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.942973] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.970559] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 596.995929] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.019151] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.045458] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.066985] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.094368] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.122760] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.163063] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.196378] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.229515] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.256354] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.279140] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.301506] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.324960] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.346887] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.369244] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.391932] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.415993] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.439727] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.463592] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.488555] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.512742] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.537350] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.558685] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.582109] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.606896] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.629379] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.652532] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.675199] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.697616] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.721680] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.750513] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.773879] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.797782] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.820755] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.843681] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.867514] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.891607] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.914819] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.936250] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.958117] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 597.987660] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.015326] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.039128] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.074932] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.099148] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.126340] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.153265] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.177042] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.199523] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.221030] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.248773] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.275750] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.298692] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.323591] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.349587] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.374867] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.402690] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.434252] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.467418] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.494706] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.522119] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.545988] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.577629] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.605841] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.634857] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.658545] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.687349] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.711707] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.736435] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.764316] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.786622] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.809077] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.831491] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.855611] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.878052] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.900897] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.925138] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.948982] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.975966] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 598.999877] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.023212] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.046099] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.069684] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.108124] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.138472] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.164368] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.188994] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.214129] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.242580] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.267253] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.297453] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.326594] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.354137] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.380752] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.405218] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.434387] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.458748] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.482598] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.503848] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.527365] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.552091] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.576023] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.598156] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.625352] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.652901] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.684105] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.714847] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.742564] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.783465] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.815259] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.846823] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.876563] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.899025] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.924445] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 599.968550] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.002553] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.047621] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.083559] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.114805] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.137688] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.162452] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.186578] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.215692] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.239922] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.262497] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.291293] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.314464] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.341884] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.369984] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.395400] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.423683] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.452780] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.476530] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.506751] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.536663] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.558548] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.588062] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.615979] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.644859] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.674912] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.699468] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.726891] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.749269] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.773175] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.797273] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.834925] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.857483] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.881250] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.903681] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.928148] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.950503] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.972507] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 600.995057] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.016541] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.037566] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.060067] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.080538] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.104662] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.126424] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.148517] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.173558] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.202263] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.228440] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.254469] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.276982] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.302548] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.325448] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.351511] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.377088] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.401025] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.423586] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.449461] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.471735] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.494310] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.518446] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.547850] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.576714] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.604958] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.625202] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.647090] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.669817] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.696072] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.730315] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.755722] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.778931] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.801122] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.826638] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.855849] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.882153] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.909291] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.935167] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.962198] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 601.992768] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.019536] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.042179] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.064529] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.087247] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.111264] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.140537] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.169338] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.196094] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.216943] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.240693] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.266774] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.302972] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.341703] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.370844] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.410890] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.436726] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.459951] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.486043] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.508293] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.530990] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.556375] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.582411] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.605688] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.630463] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.653347] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.684596] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.712071] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.743718] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.769029] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.794377] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.820696] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.842122] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.865608] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.890257] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.918959] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.941961] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.965184] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 602.991377] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.018472] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.042221] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.064415] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.088092] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.109945] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.133561] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.156499] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.180164] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.203063] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.228472] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.252547] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.276113] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.301767] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.325603] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.352974] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.375195] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.401597] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.427635] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.454980] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.482914] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.507686] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.532161] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.554553] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.580070] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.603112] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.631540] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.654104] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.678934] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.702392] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.724678] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.747480] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.773320] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.795630] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.823361] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.850454] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.873636] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.899571] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.925688] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.951608] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.974946] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 603.997911] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.020974] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.042802] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.070901] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.096146] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.126599] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.157548] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.181691] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.206172] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.229260] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.253722] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.275984] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.302517] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.341583] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.369820] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.399234] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.425197] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.449366] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.475183] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.505928] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.538890] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.563990] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.589309] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.616725] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.639848] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.666331] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.693085] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.719768] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.751544] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.776781] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.804309] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.831859] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.856760] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.881929] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.907636] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.932898] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.959890] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 604.986296] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.012831] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.034803] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.060024] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.082439] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.106578] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.130850] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.161400] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.184182] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.205499] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.228448] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.254819] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.276671] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.308750] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.335495] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.366096] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.390126] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.414322] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.438769] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.463284] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.486081] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.515856] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.545163] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.569731] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.595174] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.617569] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.641515] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.680241] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.722244] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.750534] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.777888] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.809534] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.834806] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.861309] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.886852] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.908165] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.932694] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.955331] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 605.980880] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.005513] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.027538] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.052864] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.084387] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.111285] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.141123] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.165803] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.190460] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.217946] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.249286] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.273726] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.297359] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.323387] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.347420] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.375601] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.403473] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.430669] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.459551] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.488313] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.517632] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.541327] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.571312] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.601320] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.621692] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.644833] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.666385] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.690556] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.712607] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.735320] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.762166] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.784880] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.807368] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.831672] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.855742] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.885433] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.908045] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.932496] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.955941] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 606.977256] rw_seq_cst_vs_d (29781): drop_caches: 3 [ 608.736276] Lustre: lustre-OST0000-osc-ffff9286d1b70800: disconnect after 24s idle [ 608.739100] Lustre: Skipped 1 previous similar message [ 609.748656] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 16:38:50 (1771969130) [ 609.848906] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 609.870048] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 609.909317] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 609.970851] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 609.988133] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.005039] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.072169] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.164061] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.207152] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.292735] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.333094] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.357931] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.392210] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.409493] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.426678] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.467584] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.503401] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.580090] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.603646] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.644100] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.663494] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.739422] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.917043] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.934872] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 610.971059] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.000241] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.021531] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.082539] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.140659] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.203831] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.223286] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.306114] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.323753] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.345436] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.425763] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.482517] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.518856] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.539194] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.579602] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.624628] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.643332] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.677476] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.713045] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.738117] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.757289] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.800178] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.817314] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.844530] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.891491] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.936904] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.959612] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 611.976668] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.003056] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.022981] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.062482] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.107396] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.127946] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.145797] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.170987] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.209113] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.257537] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.279098] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.297128] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.312381] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.331021] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.348246] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.452632] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.470560] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.487082] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.503328] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.598450] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.614931] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.631395] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.666345] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.739432] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.763486] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.848622] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.865271] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 612.967480] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.036582] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.062536] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.105309] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.130401] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.259167] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.275955] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.293021] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.446351] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.668073] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.752286] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.771672] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.794940] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.835940] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.853927] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.915352] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 613.932331] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.027149] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.046747] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.063677] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.082019] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.101039] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.150039] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.166403] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.280918] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.299616] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.320254] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.339769] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.432435] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.448163] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.564148] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.588149] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.638612] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.723054] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.740503] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.758923] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.776787] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.822968] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.842129] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.859085] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.874959] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.896226] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.956908] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.974097] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 614.990119] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.052768] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.124831] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.177671] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.215762] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.264304] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.325935] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.400619] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.422651] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.460377] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.504481] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.569129] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.604757] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.625024] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.698655] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.736493] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.810026] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.827210] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.845221] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.865633] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.885307] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.906844] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.949636] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.972061] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 615.986938] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.006387] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.072624] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.139056] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.162231] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.183207] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.204064] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.315959] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.416714] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.433821] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.451718] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.469197] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.491884] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.523831] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.539728] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.555597] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.613812] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.734460] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.755142] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.794616] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.849964] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.903933] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 616.923254] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.000427] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.102993] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.123989] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.142828] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.163425] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.182631] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.285802] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.309252] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.338676] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.379517] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.395277] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.435303] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.452599] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.500259] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.517387] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.560269] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.580216] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.633938] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.657146] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.679186] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.717985] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.736213] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.753994] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.820877] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 617.840709] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.009751] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.028317] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.046256] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.069509] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.106357] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.228350] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.273483] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.290107] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.307413] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.324107] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.344806] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.368823] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.386306] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.402324] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.461783] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.522787] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.580115] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.717038] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.764247] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 618.998182] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.015458] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.031975] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.047832] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.063361] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.079075] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.095231] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.111866] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.127688] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.144600] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.162704] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.180698] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.198421] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.274806] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.334856] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.394344] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.435332] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.525487] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.543475] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.607183] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.641643] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.703696] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.745539] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.846713] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 619.939967] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.020655] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.123344] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.166814] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.187374] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.223637] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.247983] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.353138] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.426948] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.447888] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.470056] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.490251] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.536529] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.595473] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.685796] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.704843] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.741242] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.789341] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.912263] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.974779] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 620.995252] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.017356] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.038917] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.096394] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.119139] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.155244] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.190388] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.229150] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.294225] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.329445] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.430846] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.506183] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.553288] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.661707] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.679830] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.699916] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.765168] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.838773] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.873696] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.895936] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.913183] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.937544] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.955460] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.973738] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 621.991311] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.008421] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.148804] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.168937] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.213542] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.231238] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.253938] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.275906] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.385596] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.486422] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.543937] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.638927] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.657138] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.743254] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.790825] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 622.888963] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.167335] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.256646] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.272909] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.289185] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.306444] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.323892] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.341634] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.412739] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.432819] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.469897] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.490039] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.511122] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.530454] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.550126] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.567649] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.586305] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.603625] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.628819] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.702426] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.741039] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.759626] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.797543] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.882313] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.920525] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.937231] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 623.954841] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.002359] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.079026] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.094873] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.145248] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.192041] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.238111] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.257433] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.339140] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.428627] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.446472] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.481551] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.497587] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.578730] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.617585] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.637137] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.685499] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.703042] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.847466] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.928307] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 624.969370] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.011211] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.047699] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.109820] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.172803] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.190088] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.275620] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.318914] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.366529] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.387044] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.433530] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.451814] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.504367] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.539183] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.563990] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.675395] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.750631] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.791271] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.832167] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.861303] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.889687] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 625.949172] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.015762] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.055897] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.129526] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.173018] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.192508] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.216205] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.235658] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.279374] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.322562] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.339096] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.373683] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.415907] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.479609] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.502556] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.564087] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.604202] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.622678] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.729943] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.756385] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.831609] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.849204] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.910277] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.945817] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.964676] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 626.981935] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.081297] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.124441] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.297833] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.336779] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.395316] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.418468] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.477456] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.519410] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.544904] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.602818] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.646760] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.668730] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.693945] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.721348] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.739288] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.759700] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.778503] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.848136] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.865854] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.914631] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.959250] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 627.984097] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.006096] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.032914] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.071131] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.112431] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.148188] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.166318] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.205481] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.321732] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.339420] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.357767] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.379365] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.420757] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.438092] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.453940] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.470151] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.487907] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.526701] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.609470] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.664874] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.691758] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.732844] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.755742] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.794219] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.812824] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.908503] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 628.945744] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.001034] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.032735] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.062668] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.085626] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.218062] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.234976] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.278968] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.306245] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.331178] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.446630] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.495266] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.514361] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.538875] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.583340] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.650060] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.667759] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.686045] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 629.746746] rw_seq_cst_vs_d (30364): drop_caches: 3 [ 632.246901] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 16:39:12 (1771969152) [ 634.336153] Lustre: lustre-OST0001-osc-ffff9286d0d6b000: disconnect after 22s idle [ 634.638951] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 16:39:15 (1771969155) [ 639.675413] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 16:39:20 (1771969160) [ 650.627219] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 16:39:31 (1771969171) [ 653.761731] Lustre: DEBUG MARKER: loop 5 [ 655.575521] Lustre: DEBUG MARKER: loop 10 [ 657.475889] Lustre: DEBUG MARKER: loop 15 [ 659.431614] Lustre: DEBUG MARKER: loop 20 [ 662.213720] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 16:39:42 (1771969182) [ 664.365385] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 16:39:45 (1771969185) [ 666.547726] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 16:39:47 (1771969187) [ 670.175211] Lustre: lustre-OST0000-osc-ffff9286d1b70800: disconnect after 24s idle [ 670.178375] Lustre: Skipped 1 previous similar message [ 685.535173] Lustre: lustre-OST0001-osc-ffff9286d0d6b000: disconnect after 23s idle [ 730.479388] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 16:40:51 (1771969251) [ 732.907301] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 16:40:53 (1771969253) [ 735.030916] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 16:40:55 (1771969255) [ 737.445515] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 16:40:58 (1771969258) [ 739.932936] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 16:41:00 (1771969260) [ 743.204214] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 16:41:03 (1771969263) [ 747.214760] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 747.744877] Lustre: DEBUG MARKER: SKIP: sanityn test_28 skipping ALWAYS excluded test 28 [ 748.273715] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 16:41:09 (1771969269) [ 752.412598] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 16:41:13 (1771969273) [ 752.525733] Lustre: *** cfs_fail_loc=314, val=0*** [ 753.567212] Lustre: *** cfs_fail_loc=314, val=0*** [ 753.569063] Lustre: Skipped 2 previous similar messages [ 755.719712] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 16:41:16 (1771969276) [ 761.684799] Lustre: *** cfs_fail_loc=314, val=0*** [ 761.707286] LustreError: 11-0: lustre-OST0000-osc-ffff9286d1b70800: operation ldlm_enqueue to node 192.168.203.152@tcp failed: rc = -107 [ 761.711055] Lustre: lustre-OST0000-osc-ffff9286d1b70800: Connection to lustre-OST0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 761.720298] LustreError: lustre-OST0000-osc-ffff9286d1b70800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 761.724963] LustreError: 41251:0:(ldlm_resource.c:1127:ldlm_resource_complain()) lustre-OST0000-osc-ffff9286d1b70800: namespace resource [0x280000400:0x7:0x0].0x0 (00000000566faa08) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 761.731538] Lustre: lustre-OST0000-osc-ffff9286d1b70800: Connection restored to (at 192.168.203.152@tcp) [ 764.314708] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 16:41:24 (1771969284) [ 764.385830] LustreError: 41838:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 767.407131] LustreError: 41838:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 awake [ 769.349211] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 769.859847] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 16:41:30 (1771969290) [ 770.384884] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 770.920982] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-Lock-Cancel ========================================================== 16:41:31 (1771969291) [ 772.581623] Lustre: lustre-MDT0000-mdc-ffff9286d0d6b000: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 777.704196] LustreError: 166-1: MGC192.168.203.152@tcp: Connection to MGS (at 192.168.203.152@tcp) was lost; in progress operations using this service will fail [ 777.711272] Lustre: Evicted from MGS (at 192.168.203.152@tcp) after server handle changed from 0xd583cecc16506a7f to 0xd583cecc16689df6 [ 777.716522] Lustre: MGC192.168.203.152@tcp: Connection restored to (at 192.168.203.152@tcp) [ 780.573981] Lustre: lustre-MDT0000-mdc-ffff9286d0d6b000: Connection restored to (at 192.168.203.152@tcp) [ 791.811013] Lustre: DEBUG MARKER: == sanityn test 33d: DNE distributed operation should trigger COS ========================================================== 16:41:52 (1771969312) [ 827.760365] Lustre: DEBUG MARKER: == sanityn test 33e: DNE local operation shouldn't trigger COS ========================================================== 16:42:28 (1771969348) [ 828.290068] Lustre: DEBUG MARKER: SKIP: sanityn test_33e Need two or more clients, have 1 [ 828.880774] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 16:42:29 (1771969349) [ 873.917502] Lustre: lustre-OST0001-osc-ffff9286d0d6b000: Connection to lustre-OST0001 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 873.924026] Lustre: Skipped 1 previous similar message [ 873.927517] LustreError: lustre-OST0001-osc-ffff9286d1b70800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 873.932404] LustreError: lustre-OST0001-osc-ffff9286d0d6b000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 873.933700] Lustre: lustre-OST0001-osc-ffff9286d1b70800: Connection restored to (at 192.168.203.152@tcp) [ 873.938456] Lustre: Skipped 2 previous similar messages [ 879.035755] Lustre: lustre-OST0000-osc-ffff9286d0d6b000: Connection to lustre-OST0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 879.042213] Lustre: Skipped 1 previous similar message [ 879.045815] LustreError: lustre-OST0000-osc-ffff9286d0d6b000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 879.050479] Lustre: lustre-OST0000-osc-ffff9286d0d6b000: Connection restored to (at 192.168.203.152@tcp) [ 892.297043] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9286d0d6b000.ost_server_uuid,osc.lustre-OST0000-osc-ffff9286d1b70800.ost_server_uuid 40 [ 892.773274] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9286d0d6b000.ost_server_uuid in FULL state after 0 sec [ 893.260524] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9286d1b70800.ost_server_uuid in FULL state after 0 sec [ 895.090345] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9286d0d6b000.ost_server_uuid,osc.lustre-OST0001-osc-ffff9286d1b70800.ost_server_uuid 40 [ 895.456111] Lustre: lustre-OST0001-osc-ffff9286d0d6b000: disconnect after 21s idle [ 895.458611] Lustre: Skipped 2 previous similar messages [ 895.599937] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9286d0d6b000.ost_server_uuid in FULL state after 0 sec [ 896.097867] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9286d1b70800.ost_server_uuid in IDLE state after 0 sec [ 898.333551] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9286d0d6b000.ost_server_uuid,osc.lustre-OST0000-osc-ffff9286d1b70800.ost_server_uuid 40 [ 898.825299] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9286d0d6b000.ost_server_uuid in FULL state after 0 sec [ 899.314112] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9286d1b70800.ost_server_uuid in FULL state after 0 sec [ 901.110954] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9286d0d6b000.ost_server_uuid,osc.lustre-OST0001-osc-ffff9286d1b70800.ost_server_uuid 40 [ 901.604788] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9286d0d6b000.ost_server_uuid in IDLE state after 0 sec [ 902.104354] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9286d1b70800.ost_server_uuid in IDLE state after 0 sec [ 905.815721] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9286d0d6b000.ost_server_uuid,osc.lustre-OST0000-osc-ffff9286d1b70800.ost_server_uuid 40 [ 906.286900] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9286d0d6b000.ost_server_uuid in IDLE state after 0 sec [ 906.778951] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9286d1b70800.ost_server_uuid in FULL state after 0 sec [ 908.650692] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9286d0d6b000.ost_server_uuid,osc.lustre-OST0001-osc-ffff9286d1b70800.ost_server_uuid 40 [ 909.148854] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9286d0d6b000.ost_server_uuid in IDLE state after 0 sec [ 909.686911] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9286d1b70800.ost_server_uuid in IDLE state after 0 sec [ 910.289161] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 16:43:50 (1771969430) [ 911.232631] Lustre: DEBUG MARKER: Race attempt 0 [ 912.796503] Lustre: DEBUG MARKER: Wait for 54328 54459 for 60 sec... [ 974.831018] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 16:44:55 (1771969495) [ 981.066535] Lustre: DEBUG MARKER: start test - cycle (0) [ 997.761133] Lustre: DEBUG MARKER: start test - cycle (1) [ 1018.085101] Lustre: DEBUG MARKER: start test - cycle (2) [ 1032.834467] Lustre: DEBUG MARKER: start test - cycle (3) [ 1048.889393] Lustre: DEBUG MARKER: start test - cycle (4) [ 1063.792177] Lustre: DEBUG MARKER: start test - cycle (5) [ 1078.473115] Lustre: DEBUG MARKER: start test - cycle (6) [ 1094.662967] Lustre: DEBUG MARKER: start test - cycle (7) [ 1109.383210] Lustre: DEBUG MARKER: start test - cycle (8) [ 1125.531931] Lustre: DEBUG MARKER: start test - cycle (9) [ 1140.384528] Lustre: DEBUG MARKER: start test - cycle (10) [ 1158.202871] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 16:47:58 (1771969678) [ 1166.815109] Lustre: lustre-OST0001-osc-ffff9286d1b70800: disconnect after 21s idle [ 1166.818379] Lustre: Skipped 2 previous similar messages [ 1169.570952] Lustre: DEBUG MARKER: == sanityn test 39a: test from 11063 ============================================================================================ 16:48:10 (1771969690) [ 1171.639614] Lustre: DEBUG MARKER: == sanityn test 39b: 11063 problem 1 ============================================================================================ 16:48:12 (1771969692) [ 1174.447814] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 16:48:15 (1771969695) [ 1177.736346] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 16:48:18 (1771969698) [ 1177.796936] Lustre: *** cfs_fail_loc=411, val=0*** [ 1179.775196] Lustre: DEBUG MARKER: == sanityn test 40a: pdirops: create vs others ======================================================================== 16:48:20 (1771969700) [ 1188.083545] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 16:48:28 (1771969708) [ 1195.992258] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 16:48:36 (1771969716) [ 1203.736335] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 16:48:44 (1771969724) [ 1211.540808] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 16:48:52 (1771969732) [ 1218.015219] Lustre: lustre-OST0001-osc-ffff9286d0d6b000: disconnect after 24s idle [ 1218.705752] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 16:48:59 (1771969739) [ 1223.504138] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 16:49:04 (1771969744) [ 1228.415653] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 16:49:09 (1771969749) [ 1233.335123] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 16:49:14 (1771969754) [ 1238.158962] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 16:49:18 (1771969758) [ 1243.080124] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 16:49:23 (1771969763) [ 1248.201541] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 16:49:28 (1771969768) [ 1253.201651] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 16:49:33 (1771969773) [ 1258.115332] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 16:49:38 (1771969778) [ 1284.575133] Lustre: lustre-OST0001-osc-ffff9286d0d6b000: disconnect after 23s idle [ 1284.577330] Lustre: Skipped 2 previous similar messages [ 1975.426815] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 17:01:36 (1771970496) [ 1980.566328] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 17:01:41 (1771970501) [ 1985.673730] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 17:01:46 (1771970506) [ 1990.788078] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 17:01:51 (1771970511) [ 1995.878258] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 17:01:56 (1771970516) [ 2001.022182] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 17:02:01 (1771970521) [ 2006.166486] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 17:02:06 (1771970526) [ 2011.278869] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 17:02:12 (1771970532) [ 2016.481817] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 17:02:17 (1771970537) [ 2016.735186] Lustre: lustre-OST0000-osc-ffff9286d0d6b000: disconnect after 24s idle [ 2033.945782] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 17:02:34 (1771970554) [ 2039.125207] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 17:02:39 (1771970559) [ 2044.299896] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 17:02:45 (1771970565) [ 2049.473150] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 17:02:50 (1771970570) [ 2054.736352] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 17:02:55 (1771970575) [ 2060.026965] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 17:03:00 (1771970580) [ 2065.447101] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 17:03:06 (1771970586) [ 2070.592963] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 17:03:11 (1771970591) [ 2075.619273] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 17:03:16 (1771970596) [ 2119.044646] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 17:03:59 (1771970639) [ 2563.389353] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 17:11:24 (1771971084) [ 2568.344992] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 17:11:29 (1771971089) [ 2573.256923] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 17:11:34 (1771971094) [ 2578.282907] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 17:11:39 (1771971099) [ 2583.460553] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 17:11:44 (1771971104) [ 2588.736940] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 17:11:49 (1771971109) [ 2593.988373] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 17:11:54 (1771971114) [ 2599.022560] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 17:11:59 (1771971119) [ 2603.989375] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 17:12:04 (1771971124) [ 2608.802061] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 17:12:09 (1771971129) [ 2626.015110] Lustre: lustre-OST0000-osc-ffff9286d0d6b000: disconnect after 21s idle [ 2626.017194] Lustre: Skipped 3 previous similar messages [ 2637.061290] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 17:12:37 (1771971157) [ 2642.057320] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 17:12:42 (1771971162) [ 2647.050065] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 17:12:47 (1771971167) [ 2652.325979] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 17:12:53 (1771971173) [ 2657.295620] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 17:12:58 (1771971178) [ 2662.271784] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 17:13:03 (1771971183) [ 2667.303618] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 17:13:08 (1771971188) [ 2671.841761] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 17:13:12 (1771971192) [ 2677.249373] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 17:13:17 (1771971197) [ 3126.075219] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 17:20:46 (1771971646) [ 3131.066853] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 17:20:51 (1771971651) [ 3136.179992] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 17:20:56 (1771971656) [ 3141.352888] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 17:21:02 (1771971662) [ 3146.591647] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 17:21:07 (1771971667) [ 3152.235953] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 17:21:12 (1771971672) [ 3157.457629] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 17:21:18 (1771971678) [ 3162.682953] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 17:21:23 (1771971683) [ 3168.069732] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 17:21:28 (1771971688) [ 3173.192685] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 17:21:33 (1771971693) [ 3178.371414] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 17:21:39 (1771971699) [ 3184.529321] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 17:21:45 (1771971705) [ 3189.866763] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 17:21:50 (1771971710) [ 3194.335121] Lustre: lustre-OST0000-osc-ffff9286d0d6b000: disconnect after 20s idle [ 3194.337112] Lustre: Skipped 1 previous similar message [ 3195.075821] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 17:21:55 (1771971715) [ 3200.871580] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 17:22:01 (1771971721) [ 3207.116210] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 17:22:07 (1771971727) [ 3213.493684] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 17:22:14 (1771971734) [ 3213.555569] LustreError: 5563:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3215.639088] LustreError: 5563:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 awake [ 3220.718969] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 17:22:21 (1771971741) [ 3224.804417] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 17:22:25 (1771971745) [ 3224.886406] LustreError: 267901:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3228.943070] LustreError: 267901:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3228.949287] LustreError: 267901:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3233.007119] LustreError: 267901:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3233.027340] LustreError: 267908:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3237.087090] LustreError: 267908:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3239.240264] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 17:22:39 (1771971759) [ 3245.808556] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 17:22:46 (1771971766) [ 3248.836520] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 17:22:49 (1771971769) [ 3252.839672] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 17:22:53 (1771971773) [ 3276.999782] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 17:23:17 (1771971797) [ 3284.676886] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 17:23:25 (1771971805) [ 3292.470819] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 17:23:33 (1771971813) [ 3305.258783] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 17:23:45 (1771971825) [ 3314.847987] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 17:23:55 (1771971835) [ 3316.955645] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3319.053081] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 17:23:59 (1771971839) [ 3321.274339] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 17:24:02 (1771971842) [ 3323.350548] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 17:24:04 (1771971844) [ 3325.373873] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 17:24:06 (1771971846) [ 3332.059169] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 17:24:12 (1771971852) [ 3344.921162] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 17:24:25 (1771971865) [ 3347.020456] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 17:24:27 (1771971867) [ 3349.080858] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 17:24:29 (1771971869) [ 3352.136179] LustreError: 11-0: lustre-MDT0000-mdc-ffff9286d1b70800: operation ldlm_enqueue to node 192.168.203.152@tcp failed: rc = -35 [ 3352.138847] LustreError: Skipped 1 previous similar message [ 3355.317321] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 17:24:36 (1771971876) [ 3355.458060] LustreError: 2265:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3357.543135] LustreError: 2265:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d awake [ 3362.494978] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 17:24:43 (1771971883) [ 3406.931321] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 17:25:27 (1771971927) [ 3409.752683] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 17:25:30 (1771971930) [ 3413.848301] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 17:25:34 (1771971934) [ 3419.154908] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 17:25:39 (1771971939) [ 3424.448271] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 17:25:45 (1771971945) [ 3432.724646] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 17:25:53 (1771971953) [ 3439.694127] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 17:26:00 (1771971960) [ 3443.047611] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 17:26:03 (1771971963) [ 3446.942176] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 17:26:07 (1771971967) [ 3454.448261] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 17:26:15 (1771971975) [ 3495.542937] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 17:26:56 (1771972016) [ 3609.358049] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with NID/JobID/OPCode expression ========================================================== 17:28:50 (1771972130) [ 3818.975192] Lustre: lustre-OST0001-osc-ffff9286d0d6b000: disconnect after 21s idle [ 3818.978351] Lustre: Skipped 11 previous similar messages [ 3890.577093] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 17:33:31 (1771972411) [ 3893.745298] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 17:33:34 (1771972414) [ 3945.074989] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 17:34:25 (1771972465) [ 3987.558326] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 17:35:08 (1771972508) [ 3991.499829] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 17:35:12 (1771972512) [ 4028.527146] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 17:35:49 (1771972549) [ 4039.351501] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 17:36:00 (1771972560) [ 4042.280812] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 17:36:03 (1771972563) [ 4054.736833] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 17:36:15 (1771972575) [ 4056.104188] LustreError: 308857:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9286d0d6b000: inode [0x200000402:0x7c0:0x0] mdc close failed: rc = -116 [ 4058.505902] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 17:36:19 (1771972579) [ 4058.952597] LustreError: 309474:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9286d0d6b000: inode [0x240000402:0x60f:0x0] mdc close failed: rc = -116 [ 4058.955885] LustreError: 309474:0:(file.c:246:ll_close_inode_openhandle()) Skipped 11 previous similar messages [ 4062.383977] LustreError: 309727:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9286d0d6b000: inode [0x240000402:0x66a:0x0] mdc close failed: rc = -116 [ 4064.217667] Lustre: dir [0x200000402:0x849:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4065.187155] Lustre: dir [0x200000402:0x868:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4072.668134] Lustre: dir [0x200000402:0x92b:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4072.721361] LustreError: 310447:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9286d0d6b000: inode [0x200000402:0x930:0x0] mdc close failed: rc = -116 [ 4072.724699] LustreError: 310447:0:(file.c:246:ll_close_inode_openhandle()) Skipped 1 previous similar message [ 4076.782137] LustreError: 310731:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9286d0d6b000: inode [0x200000402:0x98f:0x0] mdc close failed: rc = -116 [ 4076.785446] LustreError: 310731:0:(file.c:246:ll_close_inode_openhandle()) Skipped 4 previous similar messages [ 4077.335402] Lustre: dir [0x200000402:0x992:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4087.668562] Lustre: dir [0x200000402:0xa7b:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4088.947063] LustreError: 311624:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9286d0d6b000: inode [0x240000402:0x907:0x0] mdc close failed: rc = -116 [ 4088.950313] LustreError: 311624:0:(file.c:246:ll_close_inode_openhandle()) Skipped 7 previous similar messages [ 4106.383921] LustreError: 312896:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9286d0d6b000: inode [0x240000402:0xad7:0x0] mdc close failed: rc = -116 [ 4106.387239] LustreError: 312896:0:(file.c:246:ll_close_inode_openhandle()) Skipped 8 previous similar messages [ 4109.058615] Lustre: dir [0x200000402:0xc89:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4109.060780] Lustre: Skipped 1 previous similar message [ 4119.837684] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 17:37:20 (1771972640) [ 4121.848991] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 17:37:22 (1771972642) [ 4139.843421] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 17:37:40 (1771972660) [ 4140.360269] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 4140.925318] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 17:37:41 (1771972661) [ 4143.068536] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 17:37:43 (1771972663) [ 4264.998473] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 17:39:45 (1771972785) [ 4272.062377] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 17:39:52 (1771972792) [ 4455.085878] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 17:42:55 (1771972975) [ 4637.373704] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 17:45:57 (1771973157) [ 4639.901294] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 17:46:00 (1771973160) [ 4650.227764] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 17:46:10 (1771973170) [ 4650.316152] Lustre: DEBUG MARKER: write [ 4650.349041] LustreError: 20964:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 sleeping for 5000ms [ 4652.360273] Lustre: DEBUG MARKER: kill 341609 [ 4652.363240] LustreError: 341609:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 sleeping for 6000ms [ 4655.447067] LustreError: 20964:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 awake [ 4658.399136] LustreError: 341609:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 awake [ 4661.507955] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 17:46:22 (1771973182) [ 4661.652943] LustreError: 342208:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 4663.735154] LustreError: 342208:0:(fail.c:144:__cfs_fail_timeout_set()) cfs_fail_timeout interrupted [ 4669.971655] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 17:46:30 (1771973190) [ 4670.485512] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 4671.181721] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 17:46:31 (1771973191) [ 4673.991211] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 17:46:34 (1771973194) [ 4676.435320] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 17:46:36 (1771973196) [ 4679.273953] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 17:46:39 (1771973199) [ 4682.045771] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 17:46:42 (1771973202) [ 4684.939690] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 17:46:45 (1771973205) [ 4688.189799] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 17:46:48 (1771973208) [ 4691.863060] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 17:46:52 (1771973212) [ 4696.276019] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 17:46:56 (1771973216) [ 4697.131200] Lustre: *** cfs_fail_loc=415, val=0*** [ 4704.616593] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 17:47:05 (1771973225) [ 4725.741075] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 17:47:26 (1771973246) [ 4725.826797] LustreError: 283552:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 4730.927132] LustreError: 283552:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 4736.031071] LustreError: 283552:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 4736.037644] LustreError: 283552:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 4736.041532] LustreError: 283552:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 4746.135171] LustreError: 283552:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 4746.137893] LustreError: 283552:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 4756.338604] LustreError: 5571:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 4756.346152] LustreError: 5571:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 4766.439139] LustreError: 5571:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 4766.443607] LustreError: 5571:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 4791.928245] LustreError: 5571:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 4791.930991] LustreError: 5571:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 4802.127122] LustreError: 5571:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 4802.130657] LustreError: 5571:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 4809.436232] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 17:48:50 (1771973330) [ 4811.909823] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 17:48:52 (1771973332) [ 4814.713931] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 17:48:55 (1771973335) [ 4817.204584] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 17:48:57 (1771973337) [ 4822.329701] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 17:49:02 (1771973342) [ 4831.104565] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 17:49:11 (1771973351) [ 4837.606525] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 17:49:18 (1771973358) [ 4838.452469] Lustre: Unmounted lustre-client [ 4839.144747] Lustre: Unmounted lustre-client [ 4839.601564] Lustre: DEBUG MARKER: Iteration 1 [ 4839.742654] LustreError: 353720:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4839.742706] LustreError: 353721:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4839.748569] LustreError: 353720:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 4839.793300] Lustre: Mounted lustre-client [ 4839.795033] Lustre: Skipped 1 previous similar message [ 4840.280824] Lustre: Unmounted lustre-client [ 4840.282788] Lustre: Skipped 1 previous similar message [ 4841.188227] Key type lgssc unregistered [ 4841.278471] LNet: 354071:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4842.335846] LNet: Removed LNI 192.168.203.52@tcp [ 4842.562574] Key type .llcrypt unregistered [ 4842.564495] Key type ._llcrypt unregistered [ 4842.909054] alg: No test for adler32 (adler32-zlib) [ 4843.667357] Key type ._llcrypt registered [ 4843.668293] Key type .llcrypt registered [ 4843.780879] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4843.973703] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4844.198768] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4844.200879] LNet: Accept secure, port 988 [ 4845.799065] Key type lgssc registered [ 4846.249287] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4850.262787] Lustre: DEBUG MARKER: Iteration 2 [ 4850.363725] LustreError: 354866:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4850.363757] LustreError: 354867:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4850.370147] LustreError: 354866:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4851.408417] Lustre: Mounted lustre-client [ 4851.902393] Lustre: Unmounted lustre-client [ 4852.745720] Key type lgssc unregistered [ 4852.863478] LNet: 355213:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4853.920724] LNet: Removed LNI 192.168.203.52@tcp [ 4854.233803] Key type .llcrypt unregistered [ 4854.235996] Key type ._llcrypt unregistered [ 4854.603186] alg: No test for adler32 (adler32-zlib) [ 4855.354325] Key type ._llcrypt registered [ 4855.355440] Key type .llcrypt registered [ 4855.422443] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4855.529741] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4855.600467] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4855.602302] LNet: Accept secure, port 988 [ 4857.183167] Key type lgssc registered [ 4857.672194] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4860.986651] Lustre: DEBUG MARKER: Iteration 3 [ 4861.074974] LustreError: 356008:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4861.075140] LustreError: 356007:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4861.080733] LustreError: 356008:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4862.131929] Lustre: Mounted lustre-client [ 4862.133511] Lustre: Skipped 1 previous similar message [ 4862.650793] Lustre: Unmounted lustre-client [ 4862.651841] Lustre: Skipped 1 previous similar message [ 4863.641590] Key type lgssc unregistered [ 4863.749385] LNet: 356354:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4864.799702] LNet: Removed LNI 192.168.203.52@tcp [ 4865.080948] Key type .llcrypt unregistered [ 4865.082388] Key type ._llcrypt unregistered [ 4865.347639] alg: No test for adler32 (adler32-zlib) [ 4866.100291] Key type ._llcrypt registered [ 4866.101202] Key type .llcrypt registered [ 4866.159102] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4866.256228] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4866.331961] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4866.333482] LNet: Accept secure, port 988 [ 4867.911167] Key type lgssc registered [ 4868.250064] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4872.266935] Lustre: DEBUG MARKER: Iteration 4 [ 4872.409337] LustreError: 357146:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4872.409578] LustreError: 357147:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4872.414764] LustreError: 357146:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4873.467591] Lustre: Mounted lustre-client [ 4874.023064] Lustre: Unmounted lustre-client [ 4875.114796] Key type lgssc unregistered [ 4875.243523] LNet: 357495:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4876.256655] LNet: Removed LNI 192.168.203.52@tcp [ 4876.576408] Key type .llcrypt unregistered [ 4876.578507] Key type ._llcrypt unregistered [ 4876.907142] alg: No test for adler32 (adler32-zlib) [ 4877.658282] Key type ._llcrypt registered [ 4877.659325] Key type .llcrypt registered [ 4877.736419] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4877.895412] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4878.003624] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4878.007447] LNet: Accept secure, port 988 [ 4879.599114] Key type lgssc registered [ 4879.987537] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4884.202569] Lustre: DEBUG MARKER: Iteration 5 [ 4884.310305] LustreError: 358287:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4884.312767] LustreError: 358288:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4884.314514] LustreError: 358287:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4885.362531] Lustre: Mounted lustre-client [ 4885.904058] Lustre: Unmounted lustre-client [ 4887.152333] Key type lgssc unregistered [ 4887.258454] LNet: 358636:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4888.287766] LNet: Removed LNI 192.168.203.52@tcp [ 4888.510411] Key type .llcrypt unregistered [ 4888.511915] Key type ._llcrypt unregistered [ 4888.742231] alg: No test for adler32 (adler32-zlib) [ 4889.496258] Key type ._llcrypt registered [ 4889.497608] Key type .llcrypt registered [ 4889.561346] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4889.654769] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4889.724984] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4889.726366] LNet: Accept secure, port 988 [ 4891.303152] Key type lgssc registered [ 4891.703676] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4895.457320] Lustre: DEBUG MARKER: Iteration 6 [ 4895.600977] LustreError: 359429:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4895.601041] LustreError: 359430:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4895.606734] LustreError: 359429:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [ 4896.663125] Lustre: Mounted lustre-client [ 4897.213178] Lustre: Unmounted lustre-client [ 4898.277483] Key type lgssc unregistered [ 4898.390588] LNet: 359781:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4899.423569] LNet: Removed LNI 192.168.203.52@tcp [ 4899.658309] Key type .llcrypt unregistered [ 4899.659580] Key type ._llcrypt unregistered [ 4900.060410] alg: No test for adler32 (adler32-zlib) [ 4900.826286] Key type ._llcrypt registered [ 4900.827236] Key type .llcrypt registered [ 4900.898712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4901.051457] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4901.172188] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4901.174704] LNet: Accept secure, port 988 [ 4902.799120] Key type lgssc registered [ 4903.196614] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4907.726056] Lustre: DEBUG MARKER: Iteration 7 [ 4907.871607] LustreError: 360574:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4907.871645] LustreError: 360575:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4907.876168] LustreError: 360574:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4908.926838] Lustre: Mounted lustre-client [ 4908.927692] Lustre: Skipped 1 previous similar message [ 4909.422980] Lustre: Unmounted lustre-client [ 4910.492679] Key type lgssc unregistered [ 4910.611365] LNet: 360922:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4911.648718] LNet: Removed LNI 192.168.203.52@tcp [ 4912.017825] Key type .llcrypt unregistered [ 4912.019887] Key type ._llcrypt unregistered [ 4912.376861] alg: No test for adler32 (adler32-zlib) [ 4913.128338] Key type ._llcrypt registered [ 4913.129403] Key type .llcrypt registered [ 4913.187822] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4913.277709] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4913.343121] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4913.344503] LNet: Accept secure, port 988 [ 4914.919169] Key type lgssc registered [ 4915.411639] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4919.488342] Lustre: DEBUG MARKER: Iteration 8 [ 4919.661240] LustreError: 361715:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4919.663451] LustreError: 361716:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4919.666325] LustreError: 361715:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4920.722107] Lustre: Mounted lustre-client [ 4920.724336] Lustre: Skipped 1 previous similar message [ 4921.384377] Lustre: Unmounted lustre-client [ 4922.758796] Key type lgssc unregistered [ 4922.887113] LNet: 362062:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4923.936491] LNet: Removed LNI 192.168.203.52@tcp [ 4924.175024] Key type .llcrypt unregistered [ 4924.176164] Key type ._llcrypt unregistered [ 4924.472653] alg: No test for adler32 (adler32-zlib) [ 4925.223321] Key type ._llcrypt registered [ 4925.224280] Key type .llcrypt registered [ 4925.299262] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4925.417701] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4925.516863] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4925.518814] LNet: Accept secure, port 988 [ 4927.119140] Key type lgssc registered [ 4927.663264] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4931.867314] Lustre: DEBUG MARKER: Iteration 9 [ 4931.974094] LustreError: 362854:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4931.974333] LustreError: 362855:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4931.979819] LustreError: 362854:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4933.022760] Lustre: Mounted lustre-client [ 4933.527645] Lustre: Unmounted lustre-client [ 4934.522767] Key type lgssc unregistered [ 4934.632625] LNet: 363204:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4935.648548] LNet: Removed LNI 192.168.203.52@tcp [ 4936.015175] Key type .llcrypt unregistered [ 4936.016862] Key type ._llcrypt unregistered [ 4936.309417] alg: No test for adler32 (adler32-zlib) [ 4937.063301] Key type ._llcrypt registered [ 4937.064217] Key type .llcrypt registered [ 4937.145624] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4937.292069] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4937.387642] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4937.389230] LNet: Accept secure, port 988 [ 4938.991125] Key type lgssc registered [ 4939.447716] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4943.002828] Lustre: DEBUG MARKER: Iteration 10 [ 4943.106173] LustreError: 363997:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4943.106239] LustreError: 363998:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4943.112320] LustreError: 363997:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4944.152732] Lustre: Mounted lustre-client [ 4944.671691] Lustre: Unmounted lustre-client [ 4944.672759] Lustre: Skipped 1 previous similar message [ 4945.521808] Key type lgssc unregistered [ 4945.609603] LNet: 364342:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4946.656532] LNet: Removed LNI 192.168.203.52@tcp [ 4946.961652] Key type .llcrypt unregistered [ 4946.962913] Key type ._llcrypt unregistered [ 4947.229412] alg: No test for adler32 (adler32-zlib) [ 4947.980252] Key type ._llcrypt registered [ 4947.981234] Key type .llcrypt registered [ 4948.039534] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4948.173381] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4948.263852] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4948.265637] LNet: Accept secure, port 988 [ 4949.855166] Key type lgssc registered [ 4950.447871] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4955.683268] Lustre: DEBUG MARKER: Iteration 11 [ 4955.874637] LustreError: 365135:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4955.874803] LustreError: 365136:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4955.879770] LustreError: 365135:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4956.929882] Lustre: Mounted lustre-client [ 4957.478229] Lustre: Unmounted lustre-client [ 4958.303605] Key type lgssc unregistered [ 4958.404443] LNet: 365482:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4959.456170] LNet: Removed LNI 192.168.203.52@tcp [ 4959.774901] Key type .llcrypt unregistered [ 4959.776183] Key type ._llcrypt unregistered [ 4960.061586] alg: No test for adler32 (adler32-zlib) [ 4960.816334] Key type ._llcrypt registered [ 4960.817219] Key type .llcrypt registered [ 4960.896972] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4961.024172] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4961.103798] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4961.105246] LNet: Accept secure, port 988 [ 4962.687116] Key type lgssc registered [ 4963.034468] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4967.182838] Lustre: DEBUG MARKER: Iteration 12 [ 4967.313242] LustreError: 366275:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4967.315187] LustreError: 366276:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4967.319520] LustreError: 366275:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4968.364515] Lustre: Mounted lustre-client [ 4968.969984] Lustre: Unmounted lustre-client [ 4970.068989] Key type lgssc unregistered [ 4970.186286] LNet: 366621:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4971.231905] LNet: Removed LNI 192.168.203.52@tcp [ 4971.556148] Key type .llcrypt unregistered [ 4971.557628] Key type ._llcrypt unregistered [ 4971.823631] alg: No test for adler32 (adler32-zlib) [ 4972.574266] Key type ._llcrypt registered [ 4972.575166] Key type .llcrypt registered [ 4972.641864] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4972.747879] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4972.828062] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4972.829500] LNet: Accept secure, port 988 [ 4974.423139] Key type lgssc registered [ 4975.136491] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4979.326542] Lustre: DEBUG MARKER: Iteration 13 [ 4979.447968] LustreError: 367413:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4979.448069] LustreError: 367414:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4979.453155] LustreError: 367413:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 4980.500676] Lustre: Mounted lustre-client [ 4981.109101] Lustre: Unmounted lustre-client [ 4981.110096] Lustre: Skipped 1 previous similar message [ 4982.084629] Key type lgssc unregistered [ 4982.174531] LNet: 367760:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4983.200291] LNet: Removed LNI 192.168.203.52@tcp [ 4983.502488] Key type .llcrypt unregistered [ 4983.504525] Key type ._llcrypt unregistered [ 4983.809869] alg: No test for adler32 (adler32-zlib) [ 4984.561415] Key type ._llcrypt registered [ 4984.563084] Key type .llcrypt registered [ 4984.634110] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4984.734475] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4984.811662] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4984.813623] LNet: Accept secure, port 988 [ 4986.407101] Key type lgssc registered [ 4986.738314] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4990.353588] Lustre: DEBUG MARKER: Iteration 14 [ 4990.472690] LustreError: 368566:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 4990.472735] LustreError: 368568:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 4990.476835] LustreError: 368566:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 4991.527914] Lustre: Mounted lustre-client [ 4991.529037] Lustre: Skipped 1 previous similar message [ 4992.017616] Lustre: Unmounted lustre-client [ 4992.943942] Key type lgssc unregistered [ 4993.036623] LNet: 368918:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4994.080465] LNet: Removed LNI 192.168.203.52@tcp [ 4994.366924] Key type .llcrypt unregistered [ 4994.368575] Key type ._llcrypt unregistered [ 4994.768890] alg: No test for adler32 (adler32-zlib) [ 4995.520296] Key type ._llcrypt registered [ 4995.521125] Key type .llcrypt registered [ 4995.590690] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4995.707776] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 4995.805968] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4995.807587] LNet: Accept secure, port 988 [ 4997.391103] Key type lgssc registered [ 4997.971220] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5001.669583] Lustre: DEBUG MARKER: Iteration 15 [ 5001.795058] LustreError: 369712:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5001.795274] LustreError: 369713:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5001.803519] LustreError: 369712:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5002.847396] Lustre: Mounted lustre-client [ 5002.848889] Lustre: Skipped 1 previous similar message [ 5003.396441] Lustre: Unmounted lustre-client [ 5004.253524] Key type lgssc unregistered [ 5004.373521] LNet: 370058:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5005.408194] LNet: Removed LNI 192.168.203.52@tcp [ 5005.676178] Key type .llcrypt unregistered [ 5005.677310] Key type ._llcrypt unregistered [ 5005.982048] alg: No test for adler32 (adler32-zlib) [ 5006.733310] Key type ._llcrypt registered [ 5006.734227] Key type .llcrypt registered [ 5006.791278] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5006.875992] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5006.943920] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5006.945638] LNet: Accept secure, port 988 [ 5008.519136] Key type lgssc registered [ 5009.014789] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5013.237850] Lustre: DEBUG MARKER: Iteration 16 [ 5013.384847] LustreError: 370850:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5013.384987] LustreError: 370851:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5013.390806] LustreError: 370850:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5014.469908] Lustre: Mounted lustre-client [ 5015.119214] Lustre: Unmounted lustre-client [ 5015.120308] Lustre: Skipped 1 previous similar message [ 5016.238220] Key type lgssc unregistered [ 5016.350307] LNet: 371202:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5017.375878] LNet: Removed LNI 192.168.203.52@tcp [ 5017.590893] Key type .llcrypt unregistered [ 5017.591979] Key type ._llcrypt unregistered [ 5017.905061] alg: No test for adler32 (adler32-zlib) [ 5018.664321] Key type ._llcrypt registered [ 5018.665258] Key type .llcrypt registered [ 5018.729443] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5018.829723] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5018.916156] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5018.917571] LNet: Accept secure, port 988 [ 5020.503103] Key type lgssc registered [ 5020.896648] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5025.360631] Lustre: DEBUG MARKER: Iteration 17 [ 5025.517669] LustreError: 371995:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5025.517856] LustreError: 371996:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5025.523331] LustreError: 371995:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5026.575395] Lustre: Mounted lustre-client [ 5026.576845] Lustre: Skipped 1 previous similar message [ 5027.037672] Lustre: Unmounted lustre-client [ 5028.049379] Key type lgssc unregistered [ 5028.145555] LNet: 372342:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5029.152614] LNet: Removed LNI 192.168.203.52@tcp [ 5029.531057] Key type .llcrypt unregistered [ 5029.533109] Key type ._llcrypt unregistered [ 5029.920390] alg: No test for adler32 (adler32-zlib) [ 5030.680424] Key type ._llcrypt registered [ 5030.681716] Key type .llcrypt registered [ 5030.747694] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5030.844358] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5030.917289] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5030.918772] LNet: Accept secure, port 988 [ 5032.503128] Key type lgssc registered [ 5032.902590] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5037.176366] Lustre: DEBUG MARKER: Iteration 18 [ 5037.294454] LustreError: 373135:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5037.294489] LustreError: 373136:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5037.299526] LustreError: 373135:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5038.345823] Lustre: Mounted lustre-client [ 5038.346923] Lustre: Skipped 1 previous similar message [ 5038.863095] Lustre: Unmounted lustre-client [ 5039.975493] Key type lgssc unregistered [ 5040.120417] LNet: 373485:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5041.184422] LNet: Removed LNI 192.168.203.52@tcp [ 5041.530926] Key type .llcrypt unregistered [ 5041.532461] Key type ._llcrypt unregistered [ 5041.817869] alg: No test for adler32 (adler32-zlib) [ 5042.569293] Key type ._llcrypt registered [ 5042.570190] Key type .llcrypt registered [ 5042.636429] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5042.740061] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5042.811587] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5042.813105] LNet: Accept secure, port 988 [ 5044.391116] Key type lgssc registered [ 5044.899433] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5049.722285] Lustre: DEBUG MARKER: Iteration 19 [ 5049.900620] LustreError: 374279:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5049.901116] LustreError: 374278:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5049.905773] LustreError: 374279:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5050.980522] Lustre: Mounted lustre-client [ 5050.981526] Lustre: Skipped 1 previous similar message [ 5051.466575] Lustre: Unmounted lustre-client [ 5051.467619] Lustre: Skipped 1 previous similar message [ 5052.620729] Key type lgssc unregistered [ 5052.752566] LNet: 374630:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5053.792127] LNet: Removed LNI 192.168.203.52@tcp [ 5054.041354] Key type .llcrypt unregistered [ 5054.043011] Key type ._llcrypt unregistered [ 5054.335679] alg: No test for adler32 (adler32-zlib) [ 5055.086297] Key type ._llcrypt registered [ 5055.087268] Key type .llcrypt registered [ 5055.153418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5055.263158] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5055.337972] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5055.339668] LNet: Accept secure, port 988 [ 5056.919074] Key type lgssc registered [ 5057.403232] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5061.987018] Lustre: DEBUG MARKER: Iteration 20 [ 5062.130246] LustreError: 375440:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5062.130487] LustreError: 375441:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5062.136708] LustreError: 375440:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5063.193384] Lustre: Mounted lustre-client [ 5063.194588] Lustre: Skipped 1 previous similar message [ 5063.668985] Lustre: Unmounted lustre-client [ 5064.866526] Key type lgssc unregistered [ 5064.980419] LNet: 375788:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5066.016513] LNet: Removed LNI 192.168.203.52@tcp [ 5066.386488] Key type .llcrypt unregistered [ 5066.388599] Key type ._llcrypt unregistered [ 5066.671249] alg: No test for adler32 (adler32-zlib) [ 5067.422326] Key type ._llcrypt registered [ 5067.423836] Key type .llcrypt registered [ 5067.487504] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5067.572945] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5067.638034] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5067.639588] LNet: Accept secure, port 988 [ 5069.215140] Key type lgssc registered [ 5069.702989] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5074.743371] Lustre: DEBUG MARKER: Iteration 21 [ 5074.902840] LustreError: 376581:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5074.904285] LustreError: 376582:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5074.909807] LustreError: 376581:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5075.969728] Lustre: Mounted lustre-client [ 5075.971040] Lustre: Skipped 1 previous similar message [ 5076.468600] Lustre: Unmounted lustre-client [ 5077.508212] Key type lgssc unregistered [ 5077.615546] LNet: 376930:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5078.624194] LNet: Removed LNI 192.168.203.52@tcp [ 5078.855811] Key type .llcrypt unregistered [ 5078.857475] Key type ._llcrypt unregistered [ 5079.130479] alg: No test for adler32 (adler32-zlib) [ 5079.881290] Key type ._llcrypt registered [ 5079.882289] Key type .llcrypt registered [ 5079.952327] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5080.061963] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5080.153603] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5080.155555] LNet: Accept secure, port 988 [ 5081.743142] Key type lgssc registered [ 5082.343828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5087.231754] Lustre: DEBUG MARKER: Iteration 22 [ 5087.343450] LustreError: 377722:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5087.343507] LustreError: 377723:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5087.350079] LustreError: 377722:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 5088.404173] Lustre: Mounted lustre-client [ 5088.405099] Lustre: Skipped 1 previous similar message [ 5088.909119] Lustre: Unmounted lustre-client [ 5089.880587] Key type lgssc unregistered [ 5089.980611] LNet: 378069:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5091.039737] LNet: Removed LNI 192.168.203.52@tcp [ 5091.328055] Key type .llcrypt unregistered [ 5091.329584] Key type ._llcrypt unregistered [ 5091.607648] alg: No test for adler32 (adler32-zlib) [ 5092.359218] Key type ._llcrypt registered [ 5092.360216] Key type .llcrypt registered [ 5092.428370] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5092.531696] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5092.622753] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5092.624704] LNet: Accept secure, port 988 [ 5094.215134] Key type lgssc registered [ 5094.682591] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5099.405331] Lustre: DEBUG MARKER: Iteration 23 [ 5099.595856] LustreError: 378863:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5099.595897] LustreError: 378862:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5099.601357] LustreError: 378863:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5100.665750] Lustre: Mounted lustre-client [ 5101.170649] Lustre: Unmounted lustre-client [ 5102.110661] Key type lgssc unregistered [ 5102.214310] LNet: 379212:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5103.263937] LNet: Removed LNI 192.168.203.52@tcp [ 5103.538870] Key type .llcrypt unregistered [ 5103.540640] Key type ._llcrypt unregistered [ 5103.757811] alg: No test for adler32 (adler32-zlib) [ 5104.509326] Key type ._llcrypt registered [ 5104.510246] Key type .llcrypt registered [ 5104.582330] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5104.681411] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5104.758888] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5104.760342] LNet: Accept secure, port 988 [ 5106.343083] Key type lgssc registered [ 5106.725921] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5111.596392] Lustre: DEBUG MARKER: Iteration 24 [ 5111.687271] LustreError: 380005:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5111.688672] LustreError: 380006:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5111.692834] LustreError: 380005:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5112.749337] Lustre: Mounted lustre-client [ 5112.750324] Lustre: Skipped 1 previous similar message [ 5113.253300] Lustre: Unmounted lustre-client [ 5114.425595] Key type lgssc unregistered [ 5114.554755] LNet: 380352:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5115.616593] LNet: Removed LNI 192.168.203.52@tcp [ 5115.983629] Key type .llcrypt unregistered [ 5115.985855] Key type ._llcrypt unregistered [ 5116.243832] alg: No test for adler32 (adler32-zlib) [ 5116.995402] Key type ._llcrypt registered [ 5116.996521] Key type .llcrypt registered [ 5117.082364] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5117.228316] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5117.350409] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5117.352696] LNet: Accept secure, port 988 [ 5118.967102] Key type lgssc registered [ 5119.565875] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5124.498600] Lustre: DEBUG MARKER: Iteration 25 [ 5124.668322] LustreError: 381145:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5124.668475] LustreError: 381146:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5124.672743] LustreError: 381145:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5125.729348] Lustre: Mounted lustre-client [ 5125.730539] Lustre: Skipped 1 previous similar message [ 5126.205155] Lustre: Unmounted lustre-client [ 5127.184817] Key type lgssc unregistered [ 5127.303493] LNet: 381496:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5128.352457] LNet: Removed LNI 192.168.203.52@tcp [ 5128.662613] Key type .llcrypt unregistered [ 5128.663820] Key type ._llcrypt unregistered [ 5128.969033] alg: No test for adler32 (adler32-zlib) [ 5129.720343] Key type ._llcrypt registered [ 5129.721261] Key type .llcrypt registered [ 5129.799032] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5129.962091] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5130.073521] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5130.075135] LNet: Accept secure, port 988 [ 5131.695162] Key type lgssc registered [ 5132.266233] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5136.861408] Lustre: DEBUG MARKER: Iteration 26 [ 5137.003530] LustreError: 382288:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5137.003605] LustreError: 382289:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5137.009375] LustreError: 382288:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5138.063591] Lustre: Mounted lustre-client [ 5138.064564] Lustre: Skipped 1 previous similar message [ 5138.555662] Lustre: Unmounted lustre-client [ 5139.775563] Key type lgssc unregistered [ 5139.911578] LNet: 382637:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5140.959919] LNet: Removed LNI 192.168.203.52@tcp [ 5141.326561] Key type .llcrypt unregistered [ 5141.328685] Key type ._llcrypt unregistered [ 5141.649556] alg: No test for adler32 (adler32-zlib) [ 5142.402293] Key type ._llcrypt registered [ 5142.403178] Key type .llcrypt registered [ 5142.476423] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5142.598090] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5142.676068] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5142.678910] LNet: Accept secure, port 988 [ 5144.255134] Key type lgssc registered [ 5144.828716] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5149.272427] Lustre: DEBUG MARKER: Iteration 27 [ 5149.410113] LustreError: 383429:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5149.410531] LustreError: 383430:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5149.414622] LustreError: 383429:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5150.459555] Lustre: Mounted lustre-client [ 5150.460643] Lustre: Skipped 1 previous similar message [ 5151.029462] Lustre: Unmounted lustre-client [ 5151.879517] Key type lgssc unregistered [ 5151.975647] LNet: 383776:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5152.992367] LNet: Removed LNI 192.168.203.52@tcp [ 5153.285377] Key type .llcrypt unregistered [ 5153.286688] Key type ._llcrypt unregistered [ 5153.538053] alg: No test for adler32 (adler32-zlib) [ 5154.290328] Key type ._llcrypt registered [ 5154.291298] Key type .llcrypt registered [ 5154.363463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5154.517391] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5154.607942] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5154.609970] LNet: Accept secure, port 988 [ 5156.207059] Key type lgssc registered [ 5156.558553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5159.806932] Lustre: DEBUG MARKER: Iteration 28 [ 5159.920432] LustreError: 384569:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5159.920477] LustreError: 384570:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5159.925792] LustreError: 384569:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5160.974135] Lustre: Mounted lustre-client [ 5161.610254] Lustre: Unmounted lustre-client [ 5162.556801] Key type lgssc unregistered [ 5162.664807] LNet: 384917:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5163.680558] LNet: Removed LNI 192.168.203.52@tcp [ 5163.942980] Key type .llcrypt unregistered [ 5163.943988] Key type ._llcrypt unregistered [ 5164.233569] alg: No test for adler32 (adler32-zlib) [ 5164.986402] Key type ._llcrypt registered [ 5164.987377] Key type .llcrypt registered [ 5165.055190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5165.160554] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5165.236835] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5165.238937] LNet: Accept secure, port 988 [ 5166.823161] Key type lgssc registered [ 5167.161729] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5170.687483] Lustre: DEBUG MARKER: Iteration 29 [ 5170.818529] LustreError: 385710:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5170.821851] LustreError: 385711:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5170.825813] LustreError: 385710:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 5171.881572] Lustre: Mounted lustre-client [ 5172.474929] Lustre: Unmounted lustre-client [ 5173.386562] Key type lgssc unregistered [ 5173.485664] LNet: 386058:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5174.496492] LNet: Removed LNI 192.168.203.52@tcp [ 5174.710917] Key type .llcrypt unregistered [ 5174.711926] Key type ._llcrypt unregistered [ 5175.027569] alg: No test for adler32 (adler32-zlib) [ 5175.779333] Key type ._llcrypt registered [ 5175.780686] Key type .llcrypt registered [ 5175.848079] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5175.954776] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5176.039666] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5176.042440] LNet: Accept secure, port 988 [ 5177.623102] Key type lgssc registered [ 5178.084306] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5181.790759] Lustre: DEBUG MARKER: Iteration 30 [ 5181.889283] LustreError: 386852:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5181.889324] LustreError: 386853:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5181.895933] LustreError: 386852:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5182.947917] Lustre: Mounted lustre-client [ 5182.948856] Lustre: Skipped 1 previous similar message [ 5183.498390] Lustre: Unmounted lustre-client [ 5183.499332] Lustre: Skipped 1 previous similar message [ 5184.563741] Key type lgssc unregistered [ 5184.687293] LNet: 387202:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5185.695778] LNet: Removed LNI 192.168.203.52@tcp [ 5185.914825] Key type .llcrypt unregistered [ 5185.915992] Key type ._llcrypt unregistered [ 5186.173824] alg: No test for adler32 (adler32-zlib) [ 5186.929607] Key type ._llcrypt registered [ 5186.931115] Key type .llcrypt registered [ 5187.003037] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5187.106246] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5187.189683] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5187.192594] LNet: Accept secure, port 988 [ 5188.783125] Key type lgssc registered [ 5189.220983] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5192.648485] Lustre: DEBUG MARKER: Iteration 31 [ 5192.736474] LustreError: 387994:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5192.740242] LustreError: 387996:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5192.742941] LustreError: 387994:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5193.792595] Lustre: Mounted lustre-client [ 5194.313516] Lustre: Unmounted lustre-client [ 5194.314540] Lustre: Skipped 1 previous similar message [ 5195.371787] Key type lgssc unregistered [ 5195.465474] LNet: 388344:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5196.511831] LNet: Removed LNI 192.168.203.52@tcp [ 5196.733775] Key type .llcrypt unregistered [ 5196.734845] Key type ._llcrypt unregistered [ 5197.023366] alg: No test for adler32 (adler32-zlib) [ 5197.792342] Key type ._llcrypt registered [ 5197.793286] Key type .llcrypt registered [ 5197.858152] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5197.954556] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5198.026336] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5198.028842] LNet: Accept secure, port 988 [ 5199.607069] Key type lgssc registered [ 5199.936456] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5203.082066] Lustre: DEBUG MARKER: Iteration 32 [ 5203.193728] LustreError: 389136:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5203.193783] LustreError: 389137:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5203.198396] LustreError: 389136:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5204.241259] Lustre: Mounted lustre-client [ 5204.759962] Lustre: Unmounted lustre-client [ 5205.672050] Key type lgssc unregistered [ 5205.758534] LNet: 389480:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5206.815452] LNet: Removed LNI 192.168.203.52@tcp [ 5206.999638] Key type .llcrypt unregistered [ 5207.000562] Key type ._llcrypt unregistered [ 5207.246150] alg: No test for adler32 (adler32-zlib) [ 5207.997260] Key type ._llcrypt registered [ 5207.998589] Key type .llcrypt registered [ 5208.061269] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5208.152779] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5208.225217] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5208.227223] LNet: Accept secure, port 988 [ 5209.807083] Key type lgssc registered [ 5210.113867] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5213.083366] Lustre: DEBUG MARKER: Iteration 33 [ 5213.175446] LustreError: 390273:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5213.175500] LustreError: 390274:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5213.180379] LustreError: 390273:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5214.222735] Lustre: Mounted lustre-client [ 5214.223571] Lustre: Skipped 1 previous similar message [ 5214.757786] Lustre: Unmounted lustre-client [ 5215.583327] Key type lgssc unregistered [ 5215.675400] LNet: 390621:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5216.735826] LNet: Removed LNI 192.168.203.52@tcp [ 5216.930659] Key type .llcrypt unregistered [ 5216.931517] Key type ._llcrypt unregistered [ 5217.180226] alg: No test for adler32 (adler32-zlib) [ 5217.932281] Key type ._llcrypt registered [ 5217.933228] Key type .llcrypt registered [ 5217.996733] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5218.093361] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5218.169394] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5218.170977] LNet: Accept secure, port 988 [ 5219.743106] Key type lgssc registered [ 5220.078298] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5223.086532] Lustre: DEBUG MARKER: Iteration 34 [ 5223.186677] LustreError: 391415:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5223.186705] LustreError: 391416:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5223.191265] LustreError: 391415:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5224.242989] Lustre: Mounted lustre-client [ 5224.244260] Lustre: Skipped 1 previous similar message [ 5224.763356] Lustre: Unmounted lustre-client [ 5225.568649] Key type lgssc unregistered [ 5225.666425] LNet: 391762:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5226.719737] LNet: Removed LNI 192.168.203.52@tcp [ 5226.917276] Key type .llcrypt unregistered [ 5226.918203] Key type ._llcrypt unregistered [ 5227.217317] alg: No test for adler32 (adler32-zlib) [ 5227.969298] Key type ._llcrypt registered [ 5227.970270] Key type .llcrypt registered [ 5228.035822] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5228.128982] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5228.197798] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5228.199354] LNet: Accept secure, port 988 [ 5229.775065] Key type lgssc registered [ 5230.079627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5233.210477] Lustre: DEBUG MARKER: Iteration 35 [ 5233.303385] LustreError: 392555:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5233.303602] LustreError: 392556:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5233.308769] LustreError: 392555:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5234.354205] Lustre: Mounted lustre-client [ 5234.884791] Lustre: Unmounted lustre-client [ 5235.684667] Key type lgssc unregistered [ 5235.789460] LNet: 392902:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5236.831476] LNet: Removed LNI 192.168.203.52@tcp [ 5237.021464] Key type .llcrypt unregistered [ 5237.022569] Key type ._llcrypt unregistered [ 5237.307869] alg: No test for adler32 (adler32-zlib) [ 5238.059269] Key type ._llcrypt registered [ 5238.060204] Key type .llcrypt registered [ 5238.119602] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5238.207927] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5238.275723] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5238.277135] LNet: Accept secure, port 988 [ 5239.855110] Key type lgssc registered [ 5240.201771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5243.357339] Lustre: DEBUG MARKER: Iteration 36 [ 5243.456062] LustreError: 393694:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5243.459564] LustreError: 393696:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5243.461680] LustreError: 393694:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5244.517471] Lustre: Mounted lustre-client [ 5244.518968] Lustre: Skipped 1 previous similar message [ 5245.001716] Lustre: Unmounted lustre-client [ 5245.774694] Key type lgssc unregistered [ 5245.859483] LNet: 394042:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5246.879720] LNet: Removed LNI 192.168.203.52@tcp [ 5247.070600] Key type .llcrypt unregistered [ 5247.071435] Key type ._llcrypt unregistered [ 5247.314404] alg: No test for adler32 (adler32-zlib) [ 5248.065255] Key type ._llcrypt registered [ 5248.066132] Key type .llcrypt registered [ 5248.130939] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5248.219609] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5248.287204] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5248.288622] LNet: Accept secure, port 988 [ 5249.863098] Key type lgssc registered [ 5250.180326] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5253.224133] Lustre: DEBUG MARKER: Iteration 37 [ 5253.314924] LustreError: 394834:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5253.314992] LustreError: 394835:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5253.320504] LustreError: 394834:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5254.366273] Lustre: Mounted lustre-client [ 5254.367586] Lustre: Skipped 1 previous similar message [ 5254.861337] Lustre: Unmounted lustre-client [ 5255.647769] Key type lgssc unregistered [ 5255.739387] LNet: 395183:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5256.799723] LNet: Removed LNI 192.168.203.52@tcp [ 5256.992518] Key type .llcrypt unregistered [ 5256.993888] Key type ._llcrypt unregistered [ 5257.310543] alg: No test for adler32 (adler32-zlib) [ 5258.062307] Key type ._llcrypt registered [ 5258.063216] Key type .llcrypt registered [ 5258.123343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5258.215737] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5258.285211] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5258.286907] LNet: Accept secure, port 988 [ 5259.863053] Key type lgssc registered [ 5260.157245] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5263.358235] Lustre: DEBUG MARKER: Iteration 38 [ 5263.451315] LustreError: 395978:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5263.451342] LustreError: 395979:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5263.456359] LustreError: 395978:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5264.505230] Lustre: Mounted lustre-client [ 5264.994636] Lustre: Unmounted lustre-client [ 5265.797140] Key type lgssc unregistered [ 5265.876494] LNet: 396327:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5266.911470] LNet: Removed LNI 192.168.203.52@tcp [ 5267.109696] Key type .llcrypt unregistered [ 5267.110761] Key type ._llcrypt unregistered [ 5267.348492] alg: No test for adler32 (adler32-zlib) [ 5268.100238] Key type ._llcrypt registered [ 5268.101110] Key type .llcrypt registered [ 5268.188421] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5268.349410] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5268.461701] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5268.463827] LNet: Accept secure, port 988 [ 5270.079163] Key type lgssc registered [ 5270.700456] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5276.143773] Lustre: DEBUG MARKER: Iteration 39 [ 5276.279618] LustreError: 397120:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5276.279770] LustreError: 397121:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5276.285578] LustreError: 397120:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5277.339662] Lustre: Mounted lustre-client [ 5277.341054] Lustre: Skipped 1 previous similar message [ 5277.941101] Lustre: Unmounted lustre-client [ 5279.094413] Key type lgssc unregistered [ 5279.211569] LNet: 397469:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5280.224042] LNet: Removed LNI 192.168.203.52@tcp [ 5280.468435] Key type .llcrypt unregistered [ 5280.469985] Key type ._llcrypt unregistered [ 5280.743484] alg: No test for adler32 (adler32-zlib) [ 5281.504319] Key type ._llcrypt registered [ 5281.505762] Key type .llcrypt registered [ 5281.570239] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5281.688193] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5281.775547] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5281.778488] LNet: Accept secure, port 988 [ 5283.375067] Key type lgssc registered [ 5283.842367] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5288.839778] Lustre: DEBUG MARKER: Iteration 40 [ 5288.962769] LustreError: 398261:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5288.963050] LustreError: 398262:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5288.967060] LustreError: 398261:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5290.014961] Lustre: Mounted lustre-client [ 5290.016210] Lustre: Skipped 1 previous similar message [ 5290.571102] Lustre: Unmounted lustre-client [ 5290.572610] Lustre: Skipped 1 previous similar message [ 5291.564843] Key type lgssc unregistered [ 5291.650649] LNet: 398612:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5292.704702] LNet: Removed LNI 192.168.203.52@tcp [ 5293.010264] Key type .llcrypt unregistered [ 5293.012421] Key type ._llcrypt unregistered [ 5293.273171] alg: No test for adler32 (adler32-zlib) [ 5294.029276] Key type ._llcrypt registered [ 5294.030153] Key type .llcrypt registered [ 5294.091869] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5294.187843] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5294.259405] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5294.260959] LNet: Accept secure, port 988 [ 5295.839064] Key type lgssc registered [ 5296.151741] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5300.636730] Lustre: DEBUG MARKER: Iteration 41 [ 5300.769910] LustreError: 399405:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5300.770988] LustreError: 399406:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5300.775405] LustreError: 399405:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5301.821973] Lustre: Mounted lustre-client [ 5302.455021] Lustre: Unmounted lustre-client [ 5303.652205] Key type lgssc unregistered [ 5303.804268] LNet: 399749:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5304.864835] LNet: Removed LNI 192.168.203.52@tcp [ 5305.248622] Key type .llcrypt unregistered [ 5305.250839] Key type ._llcrypt unregistered [ 5305.709276] alg: No test for adler32 (adler32-zlib) [ 5306.460340] Key type ._llcrypt registered [ 5306.461309] Key type .llcrypt registered [ 5306.539209] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5306.698667] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5306.826694] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5306.828912] LNet: Accept secure, port 988 [ 5308.447098] Key type lgssc registered [ 5309.115791] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5313.960369] Lustre: DEBUG MARKER: Iteration 42 [ 5314.093506] LustreError: 400540:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5314.094825] LustreError: 400542:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5314.099864] LustreError: 400540:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5315.155405] Lustre: Mounted lustre-client [ 5315.776928] Lustre: Unmounted lustre-client [ 5317.025622] Key type lgssc unregistered [ 5317.159799] LNet: 400891:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5318.176741] LNet: Removed LNI 192.168.203.52@tcp [ 5318.583909] Key type .llcrypt unregistered [ 5318.587462] Key type ._llcrypt unregistered [ 5318.968993] alg: No test for adler32 (adler32-zlib) [ 5319.720353] Key type ._llcrypt registered [ 5319.721605] Key type .llcrypt registered [ 5319.808626] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5319.911648] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5319.979459] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5319.981044] LNet: Accept secure, port 988 [ 5321.583151] Key type lgssc registered [ 5322.158607] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5327.485861] Lustre: DEBUG MARKER: Iteration 43 [ 5327.626115] LustreError: 401684:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5327.626130] LustreError: 401685:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5327.633740] LustreError: 401684:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5328.698387] Lustre: Mounted lustre-client [ 5328.699565] Lustre: Skipped 1 previous similar message [ 5329.382617] Lustre: Unmounted lustre-client [ 5330.730301] Key type lgssc unregistered [ 5330.860011] LNet: 402036:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5331.873590] LNet: Removed LNI 192.168.203.52@tcp [ 5332.261725] Key type .llcrypt unregistered [ 5332.263269] Key type ._llcrypt unregistered [ 5332.647843] alg: No test for adler32 (adler32-zlib) [ 5333.399383] Key type ._llcrypt registered [ 5333.400693] Key type .llcrypt registered [ 5333.521358] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5333.696199] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5333.823545] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5333.827332] LNet: Accept secure, port 988 [ 5335.431091] Key type lgssc registered [ 5336.022483] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5340.728346] Lustre: DEBUG MARKER: Iteration 44 [ 5340.855308] LustreError: 402830:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5340.855396] LustreError: 402829:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5340.861125] LustreError: 402830:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5341.921118] Lustre: Mounted lustre-client [ 5341.922363] Lustre: Skipped 1 previous similar message [ 5342.543539] Lustre: Unmounted lustre-client [ 5343.648551] Key type lgssc unregistered [ 5343.764531] LNet: 403176:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5344.801514] LNet: Removed LNI 192.168.203.52@tcp [ 5345.185345] Key type .llcrypt unregistered [ 5345.187420] Key type ._llcrypt unregistered [ 5345.496923] alg: No test for adler32 (adler32-zlib) [ 5346.250289] Key type ._llcrypt registered [ 5346.251224] Key type .llcrypt registered [ 5346.356248] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5346.537193] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5346.668721] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5346.671899] LNet: Accept secure, port 988 [ 5348.287191] Key type lgssc registered [ 5348.912723] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5353.534659] Lustre: DEBUG MARKER: Iteration 45 [ 5353.658454] LustreError: 403968:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5353.658581] LustreError: 403969:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5353.662364] LustreError: 403968:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5354.723203] Lustre: Mounted lustre-client [ 5354.725302] Lustre: Skipped 1 previous similar message [ 5355.311562] Lustre: Unmounted lustre-client [ 5356.582216] Key type lgssc unregistered [ 5356.716622] LNet: 404319:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5357.729200] LNet: Removed LNI 192.168.203.52@tcp [ 5358.092079] Key type .llcrypt unregistered [ 5358.093883] Key type ._llcrypt unregistered [ 5358.358126] alg: No test for adler32 (adler32-zlib) [ 5359.114332] Key type ._llcrypt registered [ 5359.115361] Key type .llcrypt registered [ 5359.203698] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5359.341688] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5359.466220] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5359.468147] LNet: Accept secure, port 988 [ 5361.087202] Key type lgssc registered [ 5361.625284] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5366.409311] Lustre: DEBUG MARKER: Iteration 46 [ 5366.549705] LustreError: 405111:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5366.550197] LustreError: 405113:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5366.554110] LustreError: 405111:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5367.613261] Lustre: Mounted lustre-client [ 5368.305964] Lustre: Unmounted lustre-client [ 5369.739652] Key type lgssc unregistered [ 5369.886894] LNet: 405457:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5370.913292] LNet: Removed LNI 192.168.203.52@tcp [ 5371.258944] Key type .llcrypt unregistered [ 5371.261202] Key type ._llcrypt unregistered [ 5371.554045] alg: No test for adler32 (adler32-zlib) [ 5372.305341] Key type ._llcrypt registered [ 5372.306487] Key type .llcrypt registered [ 5372.412282] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5372.603756] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5372.725598] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5372.727633] LNet: Accept secure, port 988 [ 5374.335137] Key type lgssc registered [ 5374.882845] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5380.279882] Lustre: DEBUG MARKER: Iteration 47 [ 5380.434667] LustreError: 406249:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5380.435838] LustreError: 406250:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5380.440072] LustreError: 406249:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5381.500931] Lustre: Mounted lustre-client [ 5381.502587] Lustre: Skipped 1 previous similar message [ 5382.167913] Lustre: Unmounted lustre-client [ 5382.168927] Lustre: Skipped 1 previous similar message [ 5383.316422] Key type lgssc unregistered [ 5383.460245] LNet: 406599:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5384.481752] LNet: Removed LNI 192.168.203.52@tcp [ 5384.873261] Key type .llcrypt unregistered [ 5384.875191] Key type ._llcrypt unregistered [ 5385.237171] alg: No test for adler32 (adler32-zlib) [ 5385.990290] Key type ._llcrypt registered [ 5385.991250] Key type .llcrypt registered [ 5386.112734] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5386.297270] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5386.407672] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5386.409536] LNet: Accept secure, port 988 [ 5388.023127] Key type lgssc registered [ 5388.662905] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5393.833209] Lustre: DEBUG MARKER: Iteration 48 [ 5393.976850] LustreError: 407391:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5393.976876] LustreError: 407393:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5393.981439] LustreError: 407391:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5395.037256] Lustre: Mounted lustre-client [ 5395.744299] Lustre: Unmounted lustre-client [ 5395.745345] Lustre: Skipped 1 previous similar message [ 5396.828250] Key type lgssc unregistered [ 5396.920693] LNet: 407740:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5397.985340] LNet: Removed LNI 192.168.203.52@tcp [ 5398.377002] Key type .llcrypt unregistered [ 5398.379221] Key type ._llcrypt unregistered [ 5398.792195] alg: No test for adler32 (adler32-zlib) [ 5399.543271] Key type ._llcrypt registered [ 5399.544134] Key type .llcrypt registered [ 5399.623083] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5399.784001] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5399.888100] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5399.889905] LNet: Accept secure, port 988 [ 5401.487138] Key type lgssc registered [ 5402.027642] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5405.789678] Lustre: DEBUG MARKER: Iteration 49 [ 5405.931378] LustreError: 408532:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5405.931424] LustreError: 408533:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5405.938264] LustreError: 408532:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 5406.984858] Lustre: Mounted lustre-client [ 5407.476507] Lustre: Unmounted lustre-client [ 5408.383152] Key type lgssc unregistered [ 5408.483516] LNet: 408881:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5409.504209] LNet: Removed LNI 192.168.203.52@tcp [ 5409.746655] Key type .llcrypt unregistered [ 5409.748317] Key type ._llcrypt unregistered [ 5410.114497] alg: No test for adler32 (adler32-zlib) [ 5410.866330] Key type ._llcrypt registered [ 5410.867239] Key type .llcrypt registered [ 5410.974763] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5411.122743] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5411.221955] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5411.223958] LNet: Accept secure, port 988 [ 5412.815073] Key type lgssc registered [ 5413.135761] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5416.566472] Lustre: DEBUG MARKER: Iteration 50 [ 5416.696337] LustreError: 409674:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5416.696451] LustreError: 409675:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5416.701639] LustreError: 409674:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5417.745662] Lustre: Mounted lustre-client [ 5418.325211] Lustre: Unmounted lustre-client [ 5418.326213] Lustre: Skipped 1 previous similar message [ 5419.358876] Key type lgssc unregistered [ 5419.479564] LNet: 410024:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5420.512599] LNet: Removed LNI 192.168.203.52@tcp [ 5420.894553] Key type .llcrypt unregistered [ 5420.896927] Key type ._llcrypt unregistered [ 5421.183068] alg: No test for adler32 (adler32-zlib) [ 5421.935258] Key type ._llcrypt registered [ 5421.936163] Key type .llcrypt registered [ 5422.014131] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5422.140590] Lustre: Lustre: Build Version: 2.15.8_1_g8959aaa [ 5422.226631] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5422.227976] LNet: Accept secure, port 988 [ 5423.815093] Key type lgssc registered [ 5424.111694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5428.302203] Lustre: Mounted lustre-client [ 5430.984558] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 17:59:11 (1771973951) [ 5437.919193] Lustre: 411325:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1771973952/real 1771973952] req@00000000b693356c x1858049349260032/t0(0) o36->lustre-MDT0000-mdc-ffff9286d0d69000@192.168.203.152@tcp:12/10 lens 496/440 e 0 to 1 dl 1771973959 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'ln.0' [ 5437.934417] Lustre: lustre-MDT0000-mdc-ffff9286d0d69000: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5437.954178] Lustre: lustre-MDT0000-mdc-ffff9286d0d69000: Connection restored to (at 192.168.203.152@tcp) [ 5445.087150] Lustre: 411325:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1771973959/real 1771973959] req@00000000b693356c x1858049349260032/t0(0) o36->lustre-MDT0000-mdc-ffff9286d0d69000@192.168.203.152@tcp:12/10 lens 496/440 e 0 to 1 dl 1771973966 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 5445.102148] Lustre: lustre-MDT0000-mdc-ffff9286d0d69000: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5445.119503] Lustre: lustre-MDT0000-mdc-ffff9286d0d69000: Connection restored to (at 192.168.203.152@tcp) [ 5452.255250] Lustre: 411325:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1771973966/real 1771973966] req@00000000b693356c x1858049349260032/t0(0) o36->lustre-MDT0000-mdc-ffff9286d0d69000@192.168.203.152@tcp:12/10 lens 496/440 e 0 to 1 dl 1771973973 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 5452.269447] Lustre: lustre-MDT0000-mdc-ffff9286d0d69000: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5452.286525] Lustre: lustre-MDT0000-mdc-ffff9286d0d69000: Connection restored to (at 192.168.203.152@tcp) [ 5459.423178] Lustre: 411325:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1771973973/real 1771973973] req@00000000b693356c x1858049349260032/t0(0) o36->lustre-MDT0000-mdc-ffff9286d0d69000@192.168.203.152@tcp:12/10 lens 496/440 e 0 to 1 dl 1771973980 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 5459.437176] Lustre: lustre-MDT0000-mdc-ffff9286d0d69000: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5459.456400] Lustre: lustre-MDT0000-mdc-ffff9286d0d69000: Connection restored to (at 192.168.203.152@tcp) [ 5466.591228] Lustre: 411325:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1771973980/real 1771973980] req@00000000b693356c x1858049349260032/t0(0) o36->lustre-MDT0000-mdc-ffff9286d0d69000@192.168.203.152@tcp:12/10 lens 496/440 e 0 to 1 dl 1771973987 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 5466.606751] Lustre: lustre-MDT0000-mdc-ffff9286d0d69000: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5466.626479] Lustre: lustre-MDT0000-mdc-ffff9286d0d69000: Connection restored to (at 192.168.203.152@tcp) [ 5467.539502] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 17:59:48 (1771973988) [ 5474.005125] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 17:59:54 (1771973994) [ 5477.735409] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 17:59:58 (1771973998) [ 5480.153596] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 5480.925476] Lustre: DEBUG MARKER: == sanityn test complete, duration 5303 sec ============== 18:00:01 (1771974001) [ 5545.383121] Lustre: Unmounted lustre-client [ 5576.982562] Key type lgssc unregistered [ 5577.073531] LNet: 414595:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5578.079974] LNet: Removed LNI 192.168.203.52@tcp [ 5578.276533] Key type .llcrypt unregistered [ 5578.277450] Key type ._llcrypt unregistered