[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 356407320 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001013] APIC: Switch to symmetric I/O mode setup [ 0.002305] x2apic enabled [ 0.003005] Switched APIC routing to physical x2apic. [ 0.004007] kvm-guest: setup PV IPIs [ 0.006967] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008030] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009009] pid_max: default: 32768 minimum: 301 [ 0.010134] LSM: Security Framework initializing [ 0.012014] Yama: becoming mindful. [ 0.012685] SELinux: Initializing. [ 0.013047] *** VALIDATE selinux *** [ 0.021422] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025717] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026161] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027075] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028151] *** VALIDATE tmpfs *** [ 0.030305] *** VALIDATE proc *** [ 0.031216] *** VALIDATE cgroup *** [ 0.032005] *** VALIDATE cgroup2 *** [ 0.033201] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035079] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037029] Spectre V2 : User space: Vulnerable [ 0.039004] Speculative Store Bypass: Vulnerable [ 0.041730] debug: unmapping init [mem 0xffffffffa7e59000-0xffffffffa7e60fff] [ 0.043710] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044554] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045013] ... version: 2 [ 0.046006] ... bit width: 48 [ 0.046945] ... generic registers: 4 [ 0.047007] ... value mask: 0000ffffffffffff [ 0.048005] ... max period: 00007fffffffffff [ 0.049006] ... fixed-purpose events: 3 [ 0.049803] ... event mask: 000000070000000f [ 0.050223] rcu: Hierarchical SRCU implementation. [ 0.052082] smp: Bringing up secondary CPUs ... [ 0.053350] x86: Booting SMP configuration: [ 0.054015] .... node #0, CPUs: #1 #2 #3 [ 0.056538] smp: Brought up 1 node, 4 CPUs [ 0.058008] smpboot: Max logical packages: 1 [ 0.058990] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.140468] node 0 deferred pages initialised in 81ms [ 0.143108] devtmpfs: initialized [ 0.144187] x86/mm: Memory block size: 128MB [ 0.145922] gcov: version magic: 0x41383552 [ 0.148284] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.151062] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.152265] pinctrl core: initialized pinctrl subsystem [ 0.154094] [ 0.154385] ************************************************************* [ 0.156008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.157005] ** ** [ 0.159008] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.160007] ** ** [ 0.162005] ** This means that this kernel is built to expose internal ** [ 0.164007] ** IOMMU data structures, which may compromise security on ** [ 0.166006] ** your system. ** [ 0.167005] ** ** [ 0.169006] ** If you see this message and you are not debugging the ** [ 0.171007] ** kernel, report this immediately to your vendor! ** [ 0.172009] ** ** [ 0.175007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.176005] ************************************************************* [ 0.178540] NET: Registered protocol family 16 [ 0.179323] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.181028] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.183046] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.186094] cpuidle: using governor menu [ 0.187376] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.189557] PCI: Using configuration type 1 for base access [ 0.191135] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.198095] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.200024] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.203155] cryptd: max_cpu_qlen set to 1000 [ 0.205030] ACPI: Added _OSI(Module Device) [ 0.206024] ACPI: Added _OSI(Processor Device) [ 0.207013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.209013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.213785] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.218302] ACPI: Interpreter enabled [ 0.219037] ACPI: PM: (supports S0 S3 S4 S5) [ 0.221009] ACPI: Using IOAPIC for interrupt routing [ 0.222102] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.224326] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.232733] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.234026] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.237016] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.241067] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.243961] acpiphp: Slot [2] registered [ 0.245087] acpiphp: Slot [5] registered [ 0.246017] acpiphp: Slot [6] registered [ 0.246865] acpiphp: Slot [3] registered [ 0.247049] acpiphp: Slot [4] registered [ 0.248078] acpiphp: Slot [7] registered [ 0.248974] acpiphp: Slot [8] registered [ 0.250055] acpiphp: Slot [9] registered [ 0.250945] acpiphp: Slot [10] registered [ 0.252046] acpiphp: Slot [11] registered [ 0.253025] acpiphp: Slot [12] registered [ 0.253869] acpiphp: Slot [13] registered [ 0.255045] acpiphp: Slot [14] registered [ 0.255970] acpiphp: Slot [15] registered [ 0.257061] acpiphp: Slot [16] registered [ 0.257941] acpiphp: Slot [17] registered [ 0.259059] acpiphp: Slot [18] registered [ 0.259922] acpiphp: Slot [19] registered [ 0.260039] acpiphp: Slot [20] registered [ 0.260885] acpiphp: Slot [21] registered [ 0.262098] acpiphp: Slot [22] registered [ 0.263050] acpiphp: Slot [23] registered [ 0.263980] acpiphp: Slot [24] registered [ 0.265045] acpiphp: Slot [25] registered [ 0.266045] acpiphp: Slot [26] registered [ 0.266983] acpiphp: Slot [27] registered [ 0.268043] acpiphp: Slot [28] registered [ 0.268918] acpiphp: Slot [29] registered [ 0.270084] acpiphp: Slot [30] registered [ 0.271000] acpiphp: Slot [31] registered [ 0.271859] PCI host bridge to bus 0000:00 [ 0.272010] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.274009] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.275007] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.277009] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.278012] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.280012] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.281166] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.283907] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.285817] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.290798] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.293429] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.295014] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.298020] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.300012] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.301430] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.303592] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.306036] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.309703] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.311799] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.320013] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.323935] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.327835] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.332013] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.338041] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.348018] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.357195] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.362024] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.366024] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.377028] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.385162] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.386707] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.389152] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.390275] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.392159] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.396097] iommu: Default domain type: Passthrough [ 0.398421] SCSI subsystem initialized [ 0.400106] ACPI: bus type USB registered [ 0.402096] usbcore: registered new interface driver usbfs [ 0.403074] usbcore: registered new interface driver hub [ 0.405057] usbcore: registered new device driver usb [ 0.406197] pps_core: LinuxPPS API ver. 1 registered [ 0.408008] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.410049] PTP clock support registered [ 0.412114] EDAC MC: Ver: 3.0.0 [ 0.413257] PCI: Using ACPI for IRQ routing [ 0.414508] NetLabel: Initializing [ 0.415011] NetLabel: domain hash size = 128 [ 0.417008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.418080] NetLabel: unlabeled traffic allowed by default [ 0.420104] vgaarb: loaded [ 0.421184] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.422005] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.428251] clocksource: Switched to clocksource kvm-clock [ 0.508873] VFS: Disk quotas dquot_6.6.0 [ 0.510394] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.512764] *** VALIDATE ramfs *** [ 0.513924] *** VALIDATE hugetlbfs *** [ 0.515450] pnp: PnP ACPI init [ 0.517771] pnp: PnP ACPI: found 6 devices [ 0.535284] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.537675] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.539325] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.541158] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.542856] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.544590] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.546718] NET: Registered protocol family 2 [ 0.548609] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.552608] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.555260] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.559227] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.562199] TCP: Hash tables configured (established 65536 bind 65536) [ 0.564018] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.566236] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.568719] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.570541] NET: Registered protocol family 1 [ 0.572380] RPC: Registered named UNIX socket transport module. [ 0.573521] RPC: Registered udp transport module. [ 0.574414] RPC: Registered tcp transport module. [ 0.575425] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.576874] NET: Registered protocol family 44 [ 0.577719] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.578891] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.580458] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.581639] PCI: CLS 0 bytes, default 64 [ 0.583227] Unpacking initramfs... [ 1.889400] debug: unmapping init [mem 0xffff9afb3cc64000-0xffff9afb3ffcffff] [ 1.893306] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.894991] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 1.896981] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.371923] Initialise system trusted keyrings [ 2.373533] Key type blacklist registered [ 2.375371] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.384396] zbud: loaded [ 2.387171] *** VALIDATE nfs *** [ 2.388333] *** VALIDATE nfs4 *** [ 2.389713] pstore: using deflate compression [ 2.393195] Platform Keyring initialized [ 2.500637] NET: Registered protocol family 38 [ 2.503878] Key type asymmetric registered [ 2.507526] Asymmetric key parser 'x509' registered [ 2.511741] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.518043] io scheduler mq-deadline registered [ 2.519797] io scheduler kyber registered [ 2.522794] io scheduler bfq registered [ 2.525714] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.540455] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.543743] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.546547] ACPI: Power Button [PWRF] [ 2.551546] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.556621] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.565505] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.594795] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.625981] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.630955] Non-volatile memory driver v1.3 [ 2.632737] Linux agpgart interface v0.103 [ 2.659923] virtio_blk virtio1: [vda] 68264 512-byte logical blocks (35.0 MB/33.3 MiB) [ 2.663826] vda: detected capacity change from 0 to 34951168 [ 2.677982] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.681426] vdb: detected capacity change from 0 to 1073741824 [ 2.686497] libphy: Fixed MDIO Bus: probed [ 2.693584] usbcore: registered new interface driver usbserial_generic [ 2.695248] usbserial: USB Serial support registered for generic [ 2.696519] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.699456] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.700744] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.702260] mousedev: PS/2 mouse device common for all mice [ 2.703806] rtc_cmos 00:05: RTC can wake from S4 [ 2.705591] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.708406] rtc_cmos 00:05: registered as rtc0 [ 2.710692] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.712949] intel_pstate: CPU model not supported [ 2.715345] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.721991] hid: raw HID events driver (C) Jiri Kosina [ 2.722190] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.726754] usbcore: registered new interface driver usbhid [ 2.747464] usbhid: USB HID core driver [ 2.748904] drop_monitor: Initializing network drop monitor service [ 2.751505] Initializing XFRM netlink socket [ 2.753266] NET: Registered protocol family 10 [ 2.755523] Segment Routing with IPv6 [ 2.756479] NET: Registered protocol family 17 [ 2.758035] mpls_gso: MPLS GSO support [ 2.795780] RAS: Correctable Errors collector initialized. [ 2.801918] AVX version of gcm_enc/dec engaged. [ 2.805418] AES CTR mode by8 optimization enabled [ 2.900961] sched_clock: Marking stable (2900915554, 0)->(3595233092, -694317538) [ 2.907147] registered taskstats version 1 [ 2.908953] Loading compiled-in X.509 certificates [ 2.911104] zswap: loaded using pool lzo/zbud [ 2.941149] Key type big_key registered [ 2.956622] Key type encrypted registered [ 2.957883] ima: No TPM chip found, activating TPM-bypass! [ 2.959527] ima: Allocated hash algorithm: sha1 [ 2.961027] ima: No architecture policies found [ 2.962033] evm: Initialising EVM extended attributes: [ 2.963393] evm: security.selinux [ 2.964643] evm: security.ima [ 2.965521] evm: security.capability [ 2.966408] evm: HMAC attrs: 0x1 [ 2.968571] rtc_cmos 00:05: setting system clock to 2026-06-09 23:05:19 UTC (1781046319) [ 2.974690] debug: unmapping init [mem 0xffffffffa8e03000-0xffffffffa8ffffff] [ 2.978028] debug: unmapping init [mem 0xffffffffa7b82000-0xffffffffa7e58fff] [ 2.988151] Write protecting the kernel read-only data: 28672k [ 2.991807] debug: unmapping init [mem 0xffffffffa6203000-0xffffffffa63fffff] [ 2.993675] debug: unmapping init [mem 0xffffffffa6b14000-0xffffffffa6bfffff] [ 3.020436] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.027558] systemd[1]: Detected virtualization kvm. [ 3.029493] systemd[1]: Detected architecture x86-64. [ 3.030777] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.073726] systemd[1]: No hostname configured. [ 3.075827] systemd[1]: Set hostname to . [ 3.079295] random: systemd: uninitialized urandom read (16 bytes read) [ 3.081571] systemd[1]: Initializing machine ID from random generator. [ 3.138936] random: ln: uninitialized urandom read (6 bytes read) [ 3.227545] random: systemd: uninitialized urandom read (16 bytes read) [ 3.232129] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.237298] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.242627] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Setup Virtual Console... [ OK ] Reached target Swap. Starting Apply Kernel Variables... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Paths. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.982334] device-mapper: uevent: version 1.0.3 [ 3.983824] 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. [ 5.004394] virtio_net virtio0 ens2: renamed from eth0 [ 5.117633] scsi host0: ata_piix [ 5.232729] scsi host1: ata_piix [ 5.236188] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.242770] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.494323] random: crng init done [ 9.497144] random: 7 urandom warning(s) missed due to ratelimiting [ 12.250893] dracut-initqueue[591]: 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. [ 13.485844] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 16.067895] printk: systemd: 24 output lines suppressed due to ratelimiting [ 16.582162] SELinux: Disabled at runtime. [ 16.666909] 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) [ 16.682546] systemd[1]: Detected virtualization kvm. [ 16.687801] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 17.611292] systemd[1]: initrd-switch-root.service: Succeeded. [ 17.615422] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 17.629674] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 17.635943] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 17.640436] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 17.652660] systemd[1]: Starting Journal Service... Starting Journal Service... [ 17.661583] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Kernel Debug File System... [ OK ] Listening on udev Kernel Socket. Mounting POSIX Message Queue File System... [ OK ] Created slice system-getty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on udev Control Socket. Starting Create list of required st…ce nodes for the current kernel... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached targ[ 17.886356] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS et Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target RPC Port Mapper. Mounting Huge Pages File System... [ OK ] Reached target rpc_pipefs.target. Starting udev Coldplug all Devices... Starting Apply Kernel Variables... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Starting Remount Root and Kernel File Systems... [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. 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. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 18.521143] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 19.080486] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 19.087692] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 19.407510] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 19.450065] EDAC sbridge: Ver: 1.1.2 [ 21.884386] Key type dns_resolver registered [ 22.543141] NFS: Registering the id_resolver key type [ 22.550120] Key type id_resolver registered [ 22.551860] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Mark the need to relabel after reboot. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... [ OK ] Started Login Service. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ 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 System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg105-client login: [ 66.429610] libcfs: loading out-of-tree module taints kernel. [ 66.643452] alg: No test for adler32 (adler32-zlib) [ 67.394805] Key type ._llcrypt registered [ 67.400319] Key type .llcrypt registered [ 67.701316] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 68.117862] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 68.672839] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 68.678280] LNet: Accept secure, port 988 [ 70.480129] Key type lgssc registered [ 71.102382] Lustre: Echo OBD driver; http://www.lustre.org/ [ 139.186421] Lustre: Mounted lustre-client [ 142.131743] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 155.984498] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing check_logdir /tmp/testlogs/ [ 158.027394] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing yml_node [ 160.332558] Lustre: DEBUG MARKER: Client: 2.15.8.5 [ 161.676361] Lustre: DEBUG MARKER: MDS: 2.15.8.5 [ 163.037224] Lustre: DEBUG MARKER: OSS: 2.15.8.5 [ 163.965514] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Tue Jun 9 19:07:59 EDT 2026 [ 164.835133] Lustre: lustre-OST0000-osc-ffff9afb83cbf800: disconnect after 24s idle [ 168.012090] Lustre: DEBUG MARKER: excepting tests: 27 28 [ 168.710896] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 169.023945] Lustre: Mounted lustre-client [ 171.715835] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing check_config_client /mnt/lustre [ 180.483554] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 185.698052] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 19:08:21 (1781046501) [ 189.005193] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 19:08:24 (1781046504) [ 192.231956] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 19:08:28 (1781046508) [ 195.510479] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 19:08:31 (1781046511) [ 199.009798] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 19:08:34 (1781046514) [ 201.960944] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 19:08:37 (1781046517) [ 205.051315] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 19:08:40 (1781046520) [ 208.583939] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 19:08:44 (1781046524) [ 211.735870] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 19:08:47 (1781046527) [ 214.541051] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 19:08:50 (1781046530) [ 218.681380] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 19:08:54 (1781046534) [ 221.770705] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 19:08:57 (1781046537) [ 224.875362] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 19:09:00 (1781046540) [ 228.118701] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 19:09:04 (1781046544) [ 231.322177] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 19:09:07 (1781046547) [ 234.625065] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 19:09:10 (1781046550) [ 238.465577] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 19:09:14 (1781046554) [ 242.335817] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 19:09:18 (1781046558) [ 245.603480] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 19:09:21 (1781046561) [ 249.089636] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 19:09:24 (1781046564) [ 249.449471] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279507 file: /mnt/lustre/lockdir/lockfile=144115205289279506 [ 395.181627] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 19:11:50 (1781046710) [ 399.875308] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 19:11:55 (1781046715) [ 403.288528] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 19:11:59 (1781046719) [ 406.668758] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 19:12:02 (1781046722) [ 410.779702] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 19:12:06 (1781046726) [ 415.010773] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 19:12:10 (1781046730) [ 416.114476] Lustre: DEBUG MARKER: chmod [ 419.710620] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 19:12:15 (1781046735) [ 423.397849] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7207660kB free gt MAXFREE 800000kB, increase 800000 (or reduce test fs size) to proceed [ 428.878728] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 19:12:24 (1781046744) [ 548.401101] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 19:14:24 (1781046864) [ 602.492323] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 19:15:18 (1781046918) [ 677.590567] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 19:16:33 (1781046993) [ 694.492055] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 19:16:50 (1781047010) [ 697.478507] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 19:16:53 (1781047013) [ 697.940253] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 697.969698] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 697.994187] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.022333] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.051586] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.076360] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.099659] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.125508] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.153794] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.187880] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.221458] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.247176] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.272187] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.300215] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.330362] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.356356] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.381347] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.415225] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.444324] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.470041] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.498878] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.528943] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.557333] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.588928] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.622615] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.655847] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.712137] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.742773] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.767642] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.790736] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.817291] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.841535] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.877194] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.910623] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.938769] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 698.974936] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.003939] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.034268] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.058115] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.081901] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.107715] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.147702] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.182951] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.213191] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.241892] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.274847] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.304406] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.342231] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.368136] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.397864] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.428634] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.456793] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.480942] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.510327] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.537043] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.565488] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.605391] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.640989] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.670096] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.703049] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.734891] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.764484] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.807861] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.833301] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.863079] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.894073] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.918563] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.948847] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 699.973900] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.000398] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.029898] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.052499] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.087370] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.127253] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.166339] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.202730] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.241333] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.281542] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.313864] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.347774] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.383653] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.421385] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.474919] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.514583] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.542847] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.575307] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.599813] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.626580] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.657934] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.700567] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.745811] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.784953] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.816026] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.851589] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.887063] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.923605] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.958502] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 700.983712] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.011393] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.051654] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.076463] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.106334] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.143272] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.169145] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.195442] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.231579] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.261635] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.286167] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.318946] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.349429] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.379254] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.401467] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.424121] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.448867] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.475152] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.501240] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.540681] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.574470] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.601250] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.630649] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.664402] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.697586] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.740400] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.775935] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.805620] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.835907] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.858983] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.889637] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.928258] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 701.961529] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.001851] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.035487] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.092303] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.125158] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.159531] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.196094] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.227342] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.255831] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.281881] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.314345] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.342393] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.368489] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.394507] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.420155] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.452478] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.500291] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.531303] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.559336] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.583520] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.612888] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.639209] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.670400] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.698346] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.725335] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.751547] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.786208] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.815583] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.847730] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.880514] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.912740] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.945175] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 702.972958] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.002800] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.028625] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.069472] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.099697] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.136275] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.175973] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.214457] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.255138] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.293356] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.343617] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.372278] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.405547] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.433653] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.458955] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.485659] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.513270] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.536889] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.567920] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.592961] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.617507] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.645355] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.672191] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.713130] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.741202] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.769361] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.797187] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.821498] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.846104] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.868290] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.896828] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.924390] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.947105] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.971714] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 703.999140] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.027065] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.053823] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.083448] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.121480] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.165360] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.208882] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.249865] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.278966] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.312452] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.343507] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.384276] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.417216] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.459867] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.497791] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.529053] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.562194] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.601548] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.633466] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.669330] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.702330] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.728913] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.765047] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.816154] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.853962] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.892780] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.924881] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.961038] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 704.991851] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.030523] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.060315] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.086921] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.113553] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.148060] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.184098] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.222902] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.270045] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.301133] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.345353] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.370559] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.405233] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.447160] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.485920] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.521284] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.558520] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.590060] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.622458] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.652789] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.692756] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.737729] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.776139] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.805163] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.851379] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.900903] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.930586] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.962372] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 705.992735] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.029448] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.060425] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.089834] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.132376] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.160704] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.188338] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.215744] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.251787] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.282237] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.312281] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.350190] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.396369] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.432441] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.459147] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.496019] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.523463] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.561512] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.592387] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.630188] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.669972] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.713931] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.744425] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.779780] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.820767] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.850136] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.881197] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.911118] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.947475] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 706.981478] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.016642] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.045153] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.077629] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.107776] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.141270] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.175714] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.205346] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.243508] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.269974] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.296510] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.327357] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.357869] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.388832] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.424275] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.456157] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.483054] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.510947] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.545557] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.587283] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.613455] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.640245] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.665895] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.696330] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.730392] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.766994] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.824448] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.866214] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.907687] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.950803] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 707.989605] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.030773] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.068420] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.113216] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.153460] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.190980] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.227455] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.259161] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.285734] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.314860] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.356533] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.395554] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.424171] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.458141] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.503110] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.545747] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.581209] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.620981] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.677294] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.729187] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.763724] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.790846] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.825559] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.860058] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.888487] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.923146] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.950664] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 708.990326] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.024858] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.063410] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.095086] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.124847] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.165232] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.196719] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.229720] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.264655] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.312249] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.346301] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.378939] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.417346] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.451710] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.482349] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.515621] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.550371] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.583433] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.624908] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.662810] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.702094] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.738102] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.770462] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.794937] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.833473] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.860901] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.895949] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.930430] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 709.968078] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.011968] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.047239] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.080231] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.122650] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.154089] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.182253] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.211775] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.239227] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.274758] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.300242] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.324921] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.355402] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.392991] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.425506] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.457514] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.486524] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.526244] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.553057] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.585958] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.616202] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.643372] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.666969] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.695406] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.722567] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.745721] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.778345] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.813078] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.838412] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.862821] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.892654] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.929876] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 710.969317] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.000715] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.030336] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.059040] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.085294] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.118745] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.147146] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.178617] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.205385] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.234245] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.267080] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.302312] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.333601] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.363268] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.393715] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.426685] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.450601] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.479115] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.507198] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.532895] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.560481] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.591822] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.620578] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.654554] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.685271] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.717274] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.748861] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.777217] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.807582] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.833408] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.863596] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.888480] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.914751] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.951518] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 711.987868] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.012267] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.046240] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.075921] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.112023] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.145623] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.181226] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.213345] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.241183] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.270459] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.304842] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.337823] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.376421] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.407199] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.434676] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.465259] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.489262] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.515596] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.565504] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.628440] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.672958] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.707744] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.743521] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.782926] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.827647] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.859206] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.889400] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.927629] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 712.963710] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.008416] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.036716] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.073363] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.123870] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.170483] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.203710] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.233392] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.263399] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.289848] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.324149] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.358907] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.387504] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.423831] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.462290] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.491607] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.526268] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.554611] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.593657] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.630711] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.671320] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.702145] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.731766] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.763112] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.794357] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.831327] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.856596] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.879940] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.914337] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.946212] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 713.986715] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.015137] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.062354] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.100517] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.134729] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.170894] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.219639] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.254364] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.291352] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.322212] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.354567] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.397635] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.435385] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.489608] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.533320] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.572068] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.626566] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.658386] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.685133] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.718148] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.755812] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.795329] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.834295] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.884205] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.914139] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.948482] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 714.984547] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.019941] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.064080] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.104609] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.135422] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.165812] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.195230] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.233388] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.265275] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.309460] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.339686] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.366516] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.416796] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.485396] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.538860] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.582990] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.619136] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.662040] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.723622] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.763824] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.797847] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.838402] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.877810] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.924975] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 715.973674] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.017165] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.057938] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.104346] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.148535] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.188456] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.231615] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.266719] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.297413] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.336873] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.372711] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.406353] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.451201] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.497741] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.533515] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.565190] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.600389] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.625653] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.659340] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.709373] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.747187] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.771420] Lustre: lustre-OST0001-osc-ffff9afb86d8a000: disconnect after 22s idle [ 716.778941] Lustre: Skipped 1 previous similar message [ 716.786708] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.816671] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.850928] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.880679] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.912932] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.944660] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.970346] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 716.994819] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.021989] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.048809] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.075446] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.101698] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.124852] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.147391] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.172941] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.194371] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.221558] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.243879] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.273183] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.299547] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.326483] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.352286] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.376865] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.408323] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.432940] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.468897] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 717.500494] rw_seq_cst_vs_d (29833): drop_caches: 3 [ 720.811737] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 19:17:16 (1781047036) [ 720.995961] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.043759] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.117937] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.166319] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.215241] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.236201] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.275069] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.350076] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.373742] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.446967] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.472864] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.516310] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.534824] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.572911] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.596587] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.611369] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.655466] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.722412] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.739882] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.770367] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.802741] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.913757] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.938800] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.960567] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 721.999459] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.125979] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.178406] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.200312] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.228272] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.245541] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.263245] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.319137] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.395912] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.424564] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.453254] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.497956] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.651250] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.673305] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.718403] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.748068] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.879715] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 722.938567] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.026980] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.046963] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.071223] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.126095] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.225785] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.251854] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.269580] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.290216] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.378473] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.417395] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.524525] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.572096] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.731154] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.825069] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.846554] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.866826] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.885098] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.902532] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.922054] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.942941] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 723.960834] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.010074] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.053608] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.078555] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.120379] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.166651] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.182929] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.205685] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.225895] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.271192] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.288283] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.307895] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.349919] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.385643] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.403939] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.421451] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.466126] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.510887] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.562704] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.635409] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.655253] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.674757] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.693696] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.721190] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.749980] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.789272] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.876195] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.896876] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.912583] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 724.954886] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.059486] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.096725] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.112680] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.155054] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.178053] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.294823] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.328948] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.425716] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.442197] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.525456] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.542767] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.566854] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.587131] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.625239] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.647750] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.677177] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.695570] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.774805] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.796573] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.860456] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.877371] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.915943] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 725.938412] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.015623] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.093274] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.110910] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.129020] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.146826] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.174948] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.237591] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.281035] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.297177] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.357767] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.407660] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.425420] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.442550] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.459189] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.521224] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.612836] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.664911] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.813419] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.865232] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.906210] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 726.923753] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.098875] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.147433] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.212240] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.281245] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.299196] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.322544] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.401483] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.444916] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.489454] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.510080] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.550719] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.594544] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.613489] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.631960] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.651920] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.670050] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.687072] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.713466] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.771461] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.795023] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.833350] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 727.864246] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.017625] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.035380] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.072743] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.097567] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.188158] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.208035] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.226195] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.333285] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.390162] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.427987] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.457211] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.478654] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.539465] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.576203] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.598147] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.615936] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.634195] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.649817] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.677170] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.696521] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.731547] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.754888] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.780597] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 728.982088] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.030196] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.053721] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.075600] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.099081] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.173502] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.194291] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.222584] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.244749] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.298030] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.385758] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.408374] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.432209] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.453475] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.472173] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.493330] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.533678] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.558953] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.576481] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.602580] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.638230] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.821542] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.840985] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.862610] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.883295] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.940274] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 729.976630] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.000268] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.050855] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.071150] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.097810] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.115397] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.135535] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.158195] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.218601] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.291848] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.366200] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.409703] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.428749] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.495856] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.540368] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.568343] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.595132] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.615658] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.753897] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.804059] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.893510] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.912704] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 730.976847] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.016899] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.102458] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.126960] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.199606] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.238607] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.259024] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.296973] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.321367] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.348652] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.367601] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.413652] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.432725] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.473849] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.502569] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.528770] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.597643] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.632511] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.649628] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.668797] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.686255] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.704308] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.756061] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.774629] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.835927] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.945804] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 731.963846] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.001891] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.023568] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.041972] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.060549] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.082673] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.313523] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.337295] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.356461] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.374749] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.393717] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.415530] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.466685] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.489524] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.526706] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.572378] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.620114] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.638523] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.659588] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 732.680551] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.005734] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.115168] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.137769] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.158485] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.310200] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.360174] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.377942] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.480318] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.544311] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.571258] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.687290] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.817184] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.868715] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.889115] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 733.992804] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.108379] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.137538] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.271460] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.360542] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.454415] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.608252] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.634169] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.654326] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.680517] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.718787] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.760060] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.787762] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.811361] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.911147] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 734.975823] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.083981] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.107206] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.134365] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.150329] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.177595] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.299358] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.514793] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.630778] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.668775] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.756797] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.796032] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 735.916267] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.040550] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.119945] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.201892] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.300421] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.461904] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.514096] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.535605] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.595815] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.653075] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.720757] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.742253] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.765950] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.818285] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.838732] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.868240] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.893592] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 736.933830] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.192272] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.462212] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.481562] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.501964] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.522751] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.555878] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.685679] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.767136] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.853328] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.881825] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.908795] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 737.950563] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 738.153264] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 738.263809] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 738.365887] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 738.427595] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 738.470332] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 738.527898] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 738.556140] rw_seq_cst_vs_d (30424): drop_caches: 3 [ 742.368310] Lustre: lustre-OST0000-osc-ffff9afb86d8a000: disconnect after 24s idle [ 747.494700] Lustre: lustre-OST0000-osc-ffff9afb83cbf800: disconnect after 25s idle [ 780.989626] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 19:18:16 (1781047096) [ 784.671682] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 19:18:20 (1781047100) [ 790.149708] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 19:18:26 (1781047106) [ 804.576154] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 19:18:40 (1781047120) [ 808.201079] Lustre: DEBUG MARKER: loop 5 [ 810.329872] Lustre: DEBUG MARKER: loop 10 [ 812.757174] Lustre: DEBUG MARKER: loop 15 [ 814.766604] Lustre: DEBUG MARKER: loop 20 [ 818.206453] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 19:18:54 (1781047134) [ 821.205539] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 19:18:57 (1781047137) [ 824.154447] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 19:19:00 (1781047140) [ 839.648214] Lustre: lustre-OST0001-osc-ffff9afb83cbf800: disconnect after 22s idle [ 888.618906] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 19:20:04 (1781047204) [ 891.254952] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 19:20:07 (1781047207) [ 893.830417] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 19:20:09 (1781047209) [ 896.687654] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 19:20:12 (1781047212) [ 899.610538] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 19:20:15 (1781047215) [ 903.574246] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 19:20:19 (1781047219) [ 908.141331] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 908.786591] Lustre: DEBUG MARKER: SKIP: sanityn test_28 skipping ALWAYS excluded test 28 [ 909.449139] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 19:20:25 (1781047225) [ 914.127315] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 19:20:30 (1781047230) [ 914.296377] Lustre: *** cfs_fail_loc=314, val=0*** [ 915.244446] Lustre: *** cfs_fail_loc=314, val=0*** [ 915.246619] Lustre: Skipped 2 previous similar messages [ 918.128319] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 19:20:33 (1781047233) [ 927.662675] Lustre: *** cfs_fail_loc=314, val=0*** [ 927.664301] Lustre: Skipped 1 previous similar message [ 927.703652] LustreError: 11-0: lustre-OST0000-osc-ffff9afb86d8a000: operation ldlm_enqueue to node 192.168.201.105@tcp failed: rc = -107 [ 927.708072] Lustre: lustre-OST0000-osc-ffff9afb86d8a000: Connection to lustre-OST0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 927.718484] LustreError: lustre-OST0000-osc-ffff9afb86d8a000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 927.732210] LustreError: 41395:0:(ldlm_resource.c:1127:ldlm_resource_complain()) lustre-OST0000-osc-ffff9afb86d8a000: namespace resource [0x280000400:0x8:0x0].0x0 (00000000f7b0a7c6) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 927.739311] Lustre: lustre-OST0000-osc-ffff9afb86d8a000: Connection restored to (at 192.168.201.105@tcp) [ 930.792416] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 19:20:46 (1781047246) [ 930.901055] LustreError: 41982:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 933.920056] LustreError: 41982:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 awake [ 936.238754] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 936.897899] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 19:20:52 (1781047252) [ 937.585799] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 938.272508] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-Lock-Cancel ========================================================== 19:20:54 (1781047254) [ 942.054897] Lustre: lustre-MDT0000-mdc-ffff9afb83cbf800: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 947.170842] LustreError: 166-1: MGC192.168.201.105@tcp: Connection to MGS (at 192.168.201.105@tcp) was lost; in progress operations using this service will fail [ 947.176638] Lustre: Evicted from MGS (at 192.168.201.105@tcp) after server handle changed from 0x5e76d3800ef6e060 to 0x5e76d3800f07ddd6 [ 947.181268] Lustre: MGC192.168.201.105@tcp: Connection restored to (at 192.168.201.105@tcp) [ 948.539929] Lustre: lustre-MDT0000-mdc-ffff9afb83cbf800: Connection restored to (at 192.168.201.105@tcp) [ 960.362463] Lustre: DEBUG MARKER: == sanityn test 33d: DNE distributed operation should trigger COS ========================================================== 19:21:16 (1781047276) [ 1001.624280] Lustre: DEBUG MARKER: == sanityn test 33e: DNE local operation shouldn't trigger COS ========================================================== 19:21:57 (1781047317) [ 1002.221851] Lustre: DEBUG MARKER: SKIP: sanityn test_33e Need two or more clients, have 1 [ 1002.859088] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 19:21:58 (1781047318) [ 1048.505746] Lustre: lustre-OST0001-osc-ffff9afb83cbf800: Connection to lustre-OST0001 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1048.517811] Lustre: Skipped 1 previous similar message [ 1048.522517] LustreError: lustre-OST0001-osc-ffff9afb86d8a000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1048.527523] LustreError: lustre-OST0001-osc-ffff9afb83cbf800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1048.527808] Lustre: lustre-OST0001-osc-ffff9afb86d8a000: Connection restored to (at 192.168.201.105@tcp) [ 1048.536250] Lustre: Skipped 1 previous similar message [ 1053.631490] Lustre: lustre-OST0000-osc-ffff9afb83cbf800: Connection to lustre-OST0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1053.638092] Lustre: Skipped 1 previous similar message [ 1053.643728] LustreError: lustre-OST0000-osc-ffff9afb83cbf800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1053.650715] Lustre: lustre-OST0000-osc-ffff9afb83cbf800: Connection restored to (at 192.168.201.105@tcp) [ 1067.989576] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9afb83cbf800.ost_server_uuid,osc.lustre-OST0000-osc-ffff9afb86d8a000.ost_server_uuid 40 [ 1068.634685] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9afb83cbf800.ost_server_uuid in FULL state after 0 sec [ 1069.278865] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9afb86d8a000.ost_server_uuid in FULL state after 0 sec [ 1070.048224] Lustre: lustre-OST0001-osc-ffff9afb83cbf800: disconnect after 21s idle [ 1070.051561] Lustre: Skipped 3 previous similar messages [ 1071.735581] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9afb83cbf800.ost_server_uuid,osc.lustre-OST0001-osc-ffff9afb86d8a000.ost_server_uuid 40 [ 1072.434620] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9afb83cbf800.ost_server_uuid in IDLE state after 0 sec [ 1073.111906] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9afb86d8a000.ost_server_uuid in IDLE state after 0 sec [ 1076.228273] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9afb83cbf800.ost_server_uuid,osc.lustre-OST0000-osc-ffff9afb86d8a000.ost_server_uuid 40 [ 1076.892935] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9afb83cbf800.ost_server_uuid in IDLE state after 0 sec [ 1077.525126] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9afb86d8a000.ost_server_uuid in FULL state after 0 sec [ 1080.078796] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9afb83cbf800.ost_server_uuid,osc.lustre-OST0001-osc-ffff9afb86d8a000.ost_server_uuid 40 [ 1080.772100] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9afb83cbf800.ost_server_uuid in IDLE state after 0 sec [ 1081.453692] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9afb86d8a000.ost_server_uuid in IDLE state after 0 sec [ 1086.879157] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9afb83cbf800.ost_server_uuid,osc.lustre-OST0000-osc-ffff9afb86d8a000.ost_server_uuid 40 [ 1087.582376] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9afb83cbf800.ost_server_uuid in IDLE state after 0 sec [ 1088.319264] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9afb86d8a000.ost_server_uuid in FULL state after 0 sec [ 1091.023086] Lustre: DEBUG MARKER: oleg105-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9afb83cbf800.ost_server_uuid,osc.lustre-OST0001-osc-ffff9afb86d8a000.ost_server_uuid 40 [ 1091.670672] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9afb83cbf800.ost_server_uuid in IDLE state after 0 sec [ 1092.303301] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9afb86d8a000.ost_server_uuid in IDLE state after 0 sec [ 1093.012813] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 19:23:28 (1781047408) [ 1094.197112] Lustre: DEBUG MARKER: Race attempt 0 [ 1095.337109] Lustre: *** cfs_fail_loc=317, val=2147484440*** [ 1095.339556] LustreError: 54631:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 318 sleeping [ 1095.363420] LustreError: 5561:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 318 waking [ 1095.367052] LustreError: 54631:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 318 awake: rc=4976 [ 1095.373261] LustreError: 54631:0:(mdc_request.c:1484:mdc_read_page()) lustre-MDT0000-mdc-ffff9afb83cbf800: [0x200000401:0xf:0x0] lock enqueue fails: rc = -4 [ 1095.381407] Lustre: dir [0x240000402:0x2a:0x0] stripe 1 readdir failed: -4, directory is partially accessed! [ 1095.878483] Lustre: DEBUG MARKER: Wait for 54480 54610 for 60 sec... [ 1159.003710] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 19:24:34 (1781047474) [ 1165.599813] Lustre: DEBUG MARKER: start test - cycle (0) [ 1181.989648] Lustre: DEBUG MARKER: start test - cycle (1) [ 1201.348874] Lustre: DEBUG MARKER: start test - cycle (2) [ 1217.475728] Lustre: DEBUG MARKER: start test - cycle (3) [ 1237.235574] Lustre: DEBUG MARKER: start test - cycle (4) [ 1252.969954] Lustre: DEBUG MARKER: start test - cycle (5) [ 1268.408679] Lustre: DEBUG MARKER: start test - cycle (6) [ 1288.223543] Lustre: DEBUG MARKER: start test - cycle (7) [ 1303.615018] Lustre: DEBUG MARKER: start test - cycle (8) [ 1319.099619] Lustre: DEBUG MARKER: start test - cycle (9) [ 1334.599402] Lustre: DEBUG MARKER: start test - cycle (10) [ 1352.236749] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 19:27:48 (1781047668) [ 1361.888151] Lustre: lustre-OST0001-osc-ffff9afb86d8a000: disconnect after 21s idle [ 1361.891014] Lustre: Skipped 2 previous similar messages [ 1367.016702] Lustre: DEBUG MARKER: == sanityn test 39a: test from 11063 ============================================================================================ 19:28:03 (1781047683) [ 1369.361715] Lustre: DEBUG MARKER: == sanityn test 39b: 11063 problem 1 ============================================================================================ 19:28:05 (1781047685) [ 1372.653959] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 19:28:08 (1781047688) [ 1376.052665] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 19:28:12 (1781047692) [ 1376.119914] Lustre: *** cfs_fail_loc=411, val=0*** [ 1378.171986] Lustre: DEBUG MARKER: == sanityn test 40a: pdirops: create vs others ======================================================================== 19:28:14 (1781047694) [ 1386.559312] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 19:28:22 (1781047702) [ 1394.688312] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 19:28:30 (1781047710) [ 1403.222579] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 19:28:39 (1781047719) [ 1411.780793] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 19:28:47 (1781047727) [ 1413.088244] Lustre: lustre-OST0000-osc-ffff9afb83cbf800: disconnect after 20s idle [ 1419.896494] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 19:28:55 (1781047735) [ 1425.559163] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 19:29:01 (1781047741) [ 1431.251812] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 19:29:07 (1781047747) [ 1436.599601] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 19:29:12 (1781047752) [ 1442.119500] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 19:29:18 (1781047758) [ 1447.355308] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 19:29:23 (1781047763) [ 1453.146382] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 19:29:29 (1781047769) [ 1458.774640] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 19:29:34 (1781047774) [ 1464.140471] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 19:29:40 (1781047780) [ 1484.768212] Lustre: lustre-OST0001-osc-ffff9afb83cbf800: disconnect after 24s idle [ 1484.770664] Lustre: Skipped 1 previous similar message [ 2188.341196] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 19:41:44 (1781048504) [ 2193.237902] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 19:41:49 (1781048509) [ 2198.214866] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 19:41:54 (1781048514) [ 2203.068957] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 19:41:59 (1781048519) [ 2208.146586] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 19:42:04 (1781048524) [ 2213.682925] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 19:42:09 (1781048529) [ 2219.132354] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 19:42:15 (1781048535) [ 2224.312709] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 19:42:20 (1781048540) [ 2227.168206] Lustre: lustre-OST0001-osc-ffff9afb83cbf800: disconnect after 23s idle [ 2227.170343] Lustre: Skipped 1 previous similar message [ 2229.473089] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 19:42:25 (1781048545) [ 2246.794635] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 19:42:42 (1781048562) [ 2251.870646] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 19:42:47 (1781048567) [ 2257.042047] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 19:42:53 (1781048573) [ 2262.216750] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 19:42:58 (1781048578) [ 2267.490846] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 19:43:03 (1781048583) [ 2272.689428] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 19:43:08 (1781048588) [ 2278.002451] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 19:43:14 (1781048594) [ 2283.162735] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 19:43:19 (1781048599) [ 2288.443074] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 19:43:24 (1781048604) [ 2303.969127] Lustre: lustre-OST0001-osc-ffff9afb83cbf800: disconnect after 20s idle [ 2303.971537] Lustre: Skipped 1 previous similar message [ 2336.842390] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 19:44:12 (1781048652) [ 2868.267313] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 19:53:04 (1781049184) [ 2874.849609] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 19:53:10 (1781049190) [ 2881.012781] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 19:53:16 (1781049196) [ 2886.860972] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 19:53:22 (1781049202) [ 2892.219790] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 19:53:28 (1781049208) [ 2897.596960] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 19:53:33 (1781049213) [ 2902.818583] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 19:53:38 (1781049218) [ 2908.330468] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 19:53:44 (1781049224) [ 2914.032825] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 19:53:49 (1781049229) [ 2919.407635] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 19:53:55 (1781049235) [ 2938.848155] Lustre: lustre-OST0001-osc-ffff9afb83cbf800: disconnect after 24s idle [ 2938.851410] Lustre: Skipped 1 previous similar message [ 2951.342708] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 19:54:27 (1781049267) [ 2956.474153] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 19:54:32 (1781049272) [ 2961.482459] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 19:54:37 (1781049277) [ 2966.538245] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 19:54:42 (1781049282) [ 2971.644204] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 19:54:47 (1781049287) [ 2976.551816] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 19:54:52 (1781049292) [ 2981.720223] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 19:54:57 (1781049297) [ 2986.007612] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 19:55:02 (1781049302) [ 2991.116605] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 19:55:07 (1781049307) [ 3205.088295] Lustre: lustre-OST0001-osc-ffff9afb83cbf800: disconnect after 22s idle [ 3205.090426] Lustre: Skipped 1 previous similar message [ 3439.078699] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 20:02:35 (1781049755) [ 3444.244227] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 20:02:40 (1781049760) [ 3449.547210] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 20:02:45 (1781049765) [ 3454.757282] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 20:02:50 (1781049770) [ 3460.063865] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 20:02:56 (1781049776) [ 3465.225477] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 20:03:01 (1781049781) [ 3470.424968] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 20:03:06 (1781049786) [ 3475.621636] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 20:03:11 (1781049791) [ 3480.688525] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 20:03:16 (1781049796) [ 3485.863312] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 20:03:21 (1781049801) [ 3491.385285] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 20:03:27 (1781049807) [ 3497.860186] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 20:03:33 (1781049813) [ 3503.223485] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 20:03:39 (1781049819) [ 3507.168378] Lustre: lustre-OST0001-osc-ffff9afb83cbf800: disconnect after 20s idle [ 3508.686913] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 20:03:44 (1781049824) [ 3514.109106] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 20:03:50 (1781049830) [ 3519.370866] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 20:03:55 (1781049835) [ 3525.321035] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 20:04:01 (1781049841) [ 3525.391187] LustreError: 18005:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3527.472105] LustreError: 18005:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 awake [ 3532.589409] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 20:04:08 (1781049848) [ 3536.762120] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 20:04:12 (1781049852) [ 3536.840210] LustreError: 268081:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3540.896135] LustreError: 268081:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3540.903376] LustreError: 268081:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3544.960081] LustreError: 268081:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3544.971550] LustreError: 268088:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3549.032158] LustreError: 268088:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3551.255064] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 20:04:27 (1781049867) [ 3557.835534] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 20:04:33 (1781049873) [ 3560.958653] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 20:04:37 (1781049877) [ 3565.053277] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 20:04:41 (1781049881) [ 3589.238733] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 20:05:05 (1781049905) [ 3596.924357] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 20:05:13 (1781049913) [ 3604.367301] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 20:05:20 (1781049920) [ 3617.074687] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 20:05:33 (1781049933) [ 3626.592134] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 20:05:42 (1781049942) [ 3628.731253] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3630.974452] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 20:05:47 (1781049947) [ 3633.412861] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 20:05:49 (1781049949) [ 3635.590545] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 20:05:51 (1781049951) [ 3637.742480] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 20:05:53 (1781049953) [ 3645.047713] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 20:06:01 (1781049961) [ 3659.981513] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 20:06:16 (1781049976) [ 3662.344254] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 20:06:18 (1781049978) [ 3664.758634] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 20:06:20 (1781049980) [ 3667.824195] LustreError: 11-0: lustre-MDT0000-mdc-ffff9afb86d8a000: operation ldlm_enqueue to node 192.168.201.105@tcp failed: rc = -35 [ 3667.827090] LustreError: Skipped 1 previous similar message [ 3670.857644] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 20:06:26 (1781049986) [ 3671.019917] LustreError: 2266:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3673.104068] LustreError: 2266:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d awake [ 3677.966411] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 20:06:34 (1781049994) [ 3719.717695] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 20:07:15 (1781050035) [ 3722.917904] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 20:07:18 (1781050038) [ 3727.293615] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 20:07:23 (1781050043) [ 3732.851875] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 20:07:28 (1781050048) [ 3737.672687] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 20:07:33 (1781050053) [ 3745.174287] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 20:07:41 (1781050061) [ 3752.493048] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 20:07:48 (1781050068) [ 3755.843206] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 20:07:51 (1781050071) [ 3759.479367] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 20:07:55 (1781050075) [ 3766.817967] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 20:08:02 (1781050082) [ 3793.888173] Lustre: lustre-OST0001-osc-ffff9afb83cbf800: disconnect after 21s idle [ 3793.890262] Lustre: Skipped 5 previous similar messages [ 3808.681825] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 20:08:44 (1781050124) [ 3869.206105] hrtimer: interrupt took 4086256 ns [ 3942.913349] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with NID/JobID/OPCode expression ========================================================== 20:10:58 (1781050258) [ 4256.884138] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 20:16:12 (1781050572) [ 4262.449425] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 20:16:18 (1781050578) [ 4314.979759] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 20:17:10 (1781050630) [ 4362.209984] Lustre: lustre-OST0001-osc-ffff9afb83cbf800: disconnect after 23s idle [ 4362.213354] Lustre: Skipped 11 previous similar messages [ 4362.818816] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 20:17:58 (1781050678) [ 4369.394474] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 20:18:05 (1781050685) [ 4432.011944] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 20:19:07 (1781050747) [ 4451.193512] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 20:19:27 (1781050767) [ 4455.349502] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 20:19:31 (1781050771) [ 4468.792676] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 20:19:44 (1781050784) [ 4470.482920] LustreError: 308039:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9afb83cbf800: inode [0x200000402:0x7ba:0x0] mdc close failed: rc = -116 [ 4473.299988] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 20:19:49 (1781050789) [ 4473.752633] LustreError: 308647:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9afb83cbf800: inode [0x240000403:0x1ea3:0x0] mdc close failed: rc = -116 [ 4473.758278] LustreError: 308647:0:(file.c:246:ll_close_inode_openhandle()) Skipped 11 previous similar messages [ 4534.975957] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 20:20:50 (1781050850) [ 4537.570778] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 20:20:53 (1781050853) [ 4573.485892] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 20:21:29 (1781050889) [ 4574.117208] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 4574.827849] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 20:21:30 (1781050890) [ 4577.385478] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 20:21:33 (1781050893) [ 4699.703886] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 20:23:35 (1781051015) [ 4707.308711] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 20:23:43 (1781051023) [ 4889.787072] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 20:26:45 (1781051205) [ 5072.317212] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 20:29:48 (1781051388) [ 5074.612025] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 20:29:50 (1781051390) [ 5083.640252] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 20:29:59 (1781051399) [ 5083.684612] Lustre: DEBUG MARKER: write [ 5083.704102] LustreError: 6047:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 sleeping for 5000ms [ 5085.710425] Lustre: DEBUG MARKER: kill 341535 [ 5085.712750] LustreError: 341535:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 sleeping for 6000ms [ 5088.800112] LustreError: 6047:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 awake [ 5091.752141] LustreError: 341535:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 awake [ 5094.310380] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 20:30:10 (1781051410) [ 5094.450729] LustreError: 342135:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 5096.536185] LustreError: 342135:0:(fail.c:144:__cfs_fail_timeout_set()) cfs_fail_timeout interrupted [ 5102.944846] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 20:30:18 (1781051418) [ 5103.507628] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 5104.086731] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 20:30:20 (1781051420) [ 5106.331600] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 20:30:22 (1781051422) [ 5108.542533] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 20:30:24 (1781051424) [ 5110.798202] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 20:30:26 (1781051426) [ 5113.152614] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 20:30:29 (1781051429) [ 5115.433166] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 20:30:31 (1781051431) [ 5117.891736] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 20:30:33 (1781051433) [ 5121.598480] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 20:30:37 (1781051437) [ 5124.761209] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 20:30:40 (1781051440) [ 5125.453230] Lustre: *** cfs_fail_loc=415, val=0*** [ 5132.572940] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 20:30:48 (1781051448) [ 5152.138454] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 20:31:08 (1781051468) [ 5152.254906] LustreError: 5571:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5157.256187] LustreError: 5571:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 5162.352201] LustreError: 5571:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 5162.360286] LustreError: 5571:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5162.371816] LustreError: 5571:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 5172.496200] LustreError: 6047:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 5172.502438] LustreError: 6047:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 5182.545463] LustreError: 6047:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5182.552368] LustreError: 6047:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 5192.584118] LustreError: 6047:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 5192.588429] LustreError: 6047:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 5217.826559] LustreError: 5570:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5217.829822] LustreError: 5570:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 5227.952091] LustreError: 5570:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 5227.956853] LustreError: 5570:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 5239.363146] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 20:32:34 (1781051554) [ 5246.214214] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 20:32:41 (1781051561) [ 5252.471273] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 20:32:47 (1781051567) [ 5258.000538] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 20:32:53 (1781051573) [ 5265.412359] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 20:33:00 (1781051580) [ 5276.185616] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 20:33:11 (1781051591) [ 5285.638418] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 20:33:21 (1781051601) [ 5287.871874] Lustre: Unmounted lustre-client [ 5289.897298] Lustre: Unmounted lustre-client [ 5290.989899] Lustre: DEBUG MARKER: Iteration 1 [ 5291.326730] LustreError: 353652:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5291.330297] LustreError: 353651:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5291.337487] LustreError: 353652:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 5291.487420] Lustre: Mounted lustre-client [ 5292.654375] Lustre: Unmounted lustre-client [ 5294.674412] Key type lgssc unregistered [ 5294.881528] LNet: 354001:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5295.917443] LNet: Removed LNI 192.168.201.5@tcp [ 5296.473161] Key type .llcrypt unregistered [ 5296.478601] Key type ._llcrypt unregistered [ 5297.248244] alg: No test for adler32 (adler32-zlib) [ 5298.017331] Key type ._llcrypt registered [ 5298.019032] Key type .llcrypt registered [ 5298.348470] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5298.885120] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5299.535079] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5299.543078] LNet: Accept secure, port 988 [ 5301.264131] Key type lgssc registered [ 5302.407280] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5311.691864] Lustre: DEBUG MARKER: Iteration 2 [ 5311.946349] LustreError: 354795:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5311.947139] LustreError: 354796:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5311.970201] LustreError: 354795:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4988 [ 5313.196191] Lustre: Mounted lustre-client [ 5313.203023] Lustre: Skipped 1 previous similar message [ 5314.551812] Lustre: Unmounted lustre-client [ 5316.583152] Key type lgssc unregistered [ 5316.808729] LNet: 355147:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5317.858913] LNet: Removed LNI 192.168.201.5@tcp [ 5318.445971] Key type .llcrypt unregistered [ 5318.448113] Key type ._llcrypt unregistered [ 5319.132043] alg: No test for adler32 (adler32-zlib) [ 5319.889410] Key type ._llcrypt registered [ 5319.891414] Key type .llcrypt registered [ 5320.040185] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5320.251106] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5320.378110] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5320.382852] LNet: Accept secure, port 988 [ 5322.048668] Key type lgssc registered [ 5322.938445] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5330.793797] Lustre: DEBUG MARKER: Iteration 3 [ 5331.013466] LustreError: 355941:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5331.013901] LustreError: 355942:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5331.023057] LustreError: 355941:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5332.180789] Lustre: Mounted lustre-client [ 5333.434122] Lustre: Unmounted lustre-client [ 5335.220793] Key type lgssc unregistered [ 5335.406483] LNet: 356292:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5336.418597] LNet: Removed LNI 192.168.201.5@tcp [ 5336.874334] Key type .llcrypt unregistered [ 5336.875659] Key type ._llcrypt unregistered [ 5337.265707] alg: No test for adler32 (adler32-zlib) [ 5338.020489] Key type ._llcrypt registered [ 5338.022193] Key type .llcrypt registered [ 5338.133430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5338.276110] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5338.380868] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5338.382599] LNet: Accept secure, port 988 [ 5340.016634] Key type lgssc registered [ 5340.776101] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5348.900300] Lustre: DEBUG MARKER: Iteration 4 [ 5349.124114] LustreError: 357086:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5349.138911] LustreError: 357096:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5349.144745] LustreError: 357086:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4984 [ 5350.323627] Lustre: Mounted lustre-client [ 5351.617385] Lustre: Unmounted lustre-client [ 5353.663265] Key type lgssc unregistered [ 5353.837728] LNet: 357440:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5354.852309] LNet: Removed LNI 192.168.201.5@tcp [ 5355.455951] Key type .llcrypt unregistered [ 5355.460194] Key type ._llcrypt unregistered [ 5356.419191] alg: No test for adler32 (adler32-zlib) [ 5357.200257] Key type ._llcrypt registered [ 5357.202294] Key type .llcrypt registered [ 5357.387712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5357.703121] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5357.883676] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5357.890056] LNet: Accept secure, port 988 [ 5359.576125] Key type lgssc registered [ 5360.391774] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5369.361965] Lustre: DEBUG MARKER: Iteration 5 [ 5369.683511] LustreError: 358232:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5369.683541] LustreError: 358234:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5369.698810] LustreError: 358232:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 5370.934779] Lustre: Mounted lustre-client [ 5370.941504] Lustre: Skipped 1 previous similar message [ 5372.065252] Lustre: Unmounted lustre-client [ 5374.156335] Key type lgssc unregistered [ 5374.363334] LNet: 358586:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5375.395295] LNet: Removed LNI 192.168.201.5@tcp [ 5375.846607] Key type .llcrypt unregistered [ 5375.848557] Key type ._llcrypt unregistered [ 5376.474286] alg: No test for adler32 (adler32-zlib) [ 5377.244608] Key type ._llcrypt registered [ 5377.246462] Key type .llcrypt registered [ 5377.446700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5377.738546] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5377.931636] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5377.934819] LNet: Accept secure, port 988 [ 5379.608127] Key type lgssc registered [ 5380.495548] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5388.272710] Lustre: DEBUG MARKER: Iteration 6 [ 5388.472229] LustreError: 359381:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5388.472450] LustreError: 359380:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5388.485172] LustreError: 359381:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [ 5389.615550] Lustre: Mounted lustre-client [ 5390.607699] Lustre: Unmounted lustre-client [ 5392.510087] Key type lgssc unregistered [ 5392.652913] LNet: 359729:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5393.700167] LNet: Removed LNI 192.168.201.5@tcp [ 5394.221750] Key type .llcrypt unregistered [ 5394.225236] Key type ._llcrypt unregistered [ 5394.961101] alg: No test for adler32 (adler32-zlib) [ 5395.718794] Key type ._llcrypt registered [ 5395.720985] Key type .llcrypt registered [ 5395.853361] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5395.998354] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5396.108138] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5396.110425] LNet: Accept secure, port 988 [ 5397.729199] Key type lgssc registered [ 5398.976730] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5406.011264] Lustre: DEBUG MARKER: Iteration 7 [ 5406.320807] LustreError: 360522:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5406.321118] LustreError: 360523:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5406.332127] LustreError: 360522:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 5407.515595] Lustre: Mounted lustre-client [ 5408.735137] Lustre: Unmounted lustre-client [ 5410.649348] Key type lgssc unregistered [ 5410.847326] LNet: 360876:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5411.875506] LNet: Removed LNI 192.168.201.5@tcp [ 5412.433427] Key type .llcrypt unregistered [ 5412.435483] Key type ._llcrypt unregistered [ 5412.999049] alg: No test for adler32 (adler32-zlib) [ 5413.753367] Key type ._llcrypt registered [ 5413.760584] Key type .llcrypt registered [ 5414.008652] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5414.406583] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5414.683336] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5414.688682] LNet: Accept secure, port 988 [ 5416.368198] Key type lgssc registered [ 5417.462551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5426.602416] Lustre: DEBUG MARKER: Iteration 8 [ 5426.893973] LustreError: 361671:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5426.894204] LustreError: 361670:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5426.907094] LustreError: 361671:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [ 5428.103511] Lustre: Mounted lustre-client [ 5428.107364] Lustre: Skipped 1 previous similar message [ 5429.225134] Lustre: Unmounted lustre-client [ 5431.110758] Key type lgssc unregistered [ 5431.268282] LNet: 362023:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5432.295628] LNet: Removed LNI 192.168.201.5@tcp [ 5432.849915] Key type .llcrypt unregistered [ 5432.852386] Key type ._llcrypt unregistered [ 5433.417413] alg: No test for adler32 (adler32-zlib) [ 5434.174442] Key type ._llcrypt registered [ 5434.177086] Key type .llcrypt registered [ 5434.347202] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5434.579801] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5434.734767] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5434.738456] LNet: Accept secure, port 988 [ 5436.384198] Key type lgssc registered [ 5437.244739] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5444.450531] Lustre: DEBUG MARKER: Iteration 9 [ 5444.631649] LustreError: 362816:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5444.632257] LustreError: 362817:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5444.650893] LustreError: 362816:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 5445.825372] Lustre: Mounted lustre-client [ 5446.751102] Lustre: Unmounted lustre-client [ 5448.306978] Key type lgssc unregistered [ 5448.456667] LNet: 363169:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5449.504903] LNet: Removed LNI 192.168.201.5@tcp [ 5449.851975] Key type .llcrypt unregistered [ 5449.854261] Key type ._llcrypt unregistered [ 5450.370170] alg: No test for adler32 (adler32-zlib) [ 5451.130862] Key type ._llcrypt registered [ 5451.132974] Key type .llcrypt registered [ 5451.235733] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5451.477382] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5451.663901] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5451.667647] LNet: Accept secure, port 988 [ 5453.312144] Key type lgssc registered [ 5453.988216] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5460.993762] Lustre: DEBUG MARKER: Iteration 10 [ 5461.257938] LustreError: 363962:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5461.259094] LustreError: 363964:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5461.270226] LustreError: 363962:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 5462.461169] Lustre: Mounted lustre-client [ 5463.467118] Lustre: Unmounted lustre-client [ 5465.330607] Key type lgssc unregistered [ 5465.491105] LNet: 364317:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5466.530472] LNet: Removed LNI 192.168.201.5@tcp [ 5467.033560] Key type .llcrypt unregistered [ 5467.035674] Key type ._llcrypt unregistered [ 5467.681600] alg: No test for adler32 (adler32-zlib) [ 5468.442372] Key type ._llcrypt registered [ 5468.444706] Key type .llcrypt registered [ 5468.604557] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5468.844424] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5469.002136] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5469.005874] LNet: Accept secure, port 988 [ 5470.633897] Key type lgssc registered [ 5471.422584] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5479.385790] Lustre: DEBUG MARKER: Iteration 11 [ 5479.597747] LustreError: 365110:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5479.598597] LustreError: 365112:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5479.622818] LustreError: 365110:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4987 [ 5480.866904] Lustre: Mounted lustre-client [ 5480.880255] Lustre: Skipped 1 previous similar message [ 5482.326151] Lustre: Unmounted lustre-client [ 5484.367754] Key type lgssc unregistered [ 5484.628774] LNet: 365463:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5485.676516] LNet: Removed LNI 192.168.201.5@tcp [ 5486.229935] Key type .llcrypt unregistered [ 5486.234227] Key type ._llcrypt unregistered [ 5486.950494] alg: No test for adler32 (adler32-zlib) [ 5487.709486] Key type ._llcrypt registered [ 5487.710934] Key type .llcrypt registered [ 5487.843477] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5488.047428] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5488.194857] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5488.198442] LNet: Accept secure, port 988 [ 5489.856170] Key type lgssc registered [ 5490.733856] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5498.873629] Lustre: DEBUG MARKER: Iteration 12 [ 5499.076143] LustreError: 366257:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5499.076472] LustreError: 366258:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5499.083783] LustreError: 366257:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5500.275298] Lustre: Mounted lustre-client [ 5500.278309] Lustre: Skipped 1 previous similar message [ 5501.329493] Lustre: Unmounted lustre-client [ 5503.211175] Key type lgssc unregistered [ 5503.410449] LNet: 366610:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5504.418471] LNet: Removed LNI 192.168.201.5@tcp [ 5504.847429] Key type .llcrypt unregistered [ 5504.849293] Key type ._llcrypt unregistered [ 5505.284144] alg: No test for adler32 (adler32-zlib) [ 5506.037436] Key type ._llcrypt registered [ 5506.038838] Key type .llcrypt registered [ 5506.173668] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5506.383846] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5506.509865] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5506.514466] LNet: Accept secure, port 988 [ 5508.168187] Key type lgssc registered [ 5508.867660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5515.669897] Lustre: DEBUG MARKER: Iteration 13 [ 5515.884741] LustreError: 367404:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5515.887478] LustreError: 367405:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5515.889979] LustreError: 367404:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 5517.046408] Lustre: Mounted lustre-client [ 5518.109948] Lustre: Unmounted lustre-client [ 5519.528758] Key type lgssc unregistered [ 5519.663547] LNet: 367754:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5520.673895] LNet: Removed LNI 192.168.201.5@tcp [ 5521.107524] Key type .llcrypt unregistered [ 5521.110174] Key type ._llcrypt unregistered [ 5521.659266] alg: No test for adler32 (adler32-zlib) [ 5522.415465] Key type ._llcrypt registered [ 5522.418383] Key type .llcrypt registered [ 5522.543430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5522.760529] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5522.930888] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5522.933794] LNet: Accept secure, port 988 [ 5524.568126] Key type lgssc registered [ 5525.322617] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5531.705523] Lustre: DEBUG MARKER: Iteration 14 [ 5531.900825] LustreError: 368546:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5531.905586] LustreError: 368548:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5531.915727] LustreError: 368546:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [ 5533.102723] Lustre: Mounted lustre-client [ 5534.159966] Lustre: Unmounted lustre-client [ 5535.646716] Key type lgssc unregistered [ 5535.809457] LNet: 368899:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5536.867501] LNet: Removed LNI 192.168.201.5@tcp [ 5537.262119] Key type .llcrypt unregistered [ 5537.263988] Key type ._llcrypt unregistered [ 5537.596323] alg: No test for adler32 (adler32-zlib) [ 5538.348918] Key type ._llcrypt registered [ 5538.355950] Key type .llcrypt registered [ 5538.503995] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5538.696378] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5538.824262] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5538.828857] LNet: Accept secure, port 988 [ 5540.440222] Key type lgssc registered [ 5541.191925] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5547.159196] Lustre: DEBUG MARKER: Iteration 15 [ 5547.347543] LustreError: 369691:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5547.352466] LustreError: 369699:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5547.358524] LustreError: 369691:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 5548.515379] Lustre: Mounted lustre-client [ 5549.643161] Lustre: Unmounted lustre-client [ 5551.585354] Key type lgssc unregistered [ 5551.730600] LNet: 370046:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5552.737902] LNet: Removed LNI 192.168.201.5@tcp [ 5553.103137] Key type .llcrypt unregistered [ 5553.104802] Key type ._llcrypt unregistered [ 5553.558892] alg: No test for adler32 (adler32-zlib) [ 5554.317444] Key type ._llcrypt registered [ 5554.318987] Key type .llcrypt registered [ 5554.406818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5554.547156] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5554.665497] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5554.668736] LNet: Accept secure, port 988 [ 5556.280106] Key type lgssc registered [ 5556.822051] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5563.380777] Lustre: DEBUG MARKER: Iteration 16 [ 5563.496437] LustreError: 370840:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5563.497459] LustreError: 370839:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5563.504099] LustreError: 370840:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 5564.616316] Lustre: Mounted lustre-client [ 5564.620629] Lustre: Skipped 1 previous similar message [ 5565.457343] Lustre: Unmounted lustre-client [ 5567.039808] Key type lgssc unregistered [ 5567.184953] LNet: 371188:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5568.226628] LNet: Removed LNI 192.168.201.5@tcp [ 5568.546978] Key type .llcrypt unregistered [ 5568.548419] Key type ._llcrypt unregistered [ 5568.895685] alg: No test for adler32 (adler32-zlib) [ 5569.647334] Key type ._llcrypt registered [ 5569.648605] Key type .llcrypt registered [ 5569.766362] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5569.967357] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5570.086319] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5570.088418] LNet: Accept secure, port 988 [ 5571.705959] Key type lgssc registered [ 5572.381110] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5579.462181] Lustre: DEBUG MARKER: Iteration 17 [ 5579.604176] LustreError: 371981:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5579.609102] LustreError: 371982:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5579.616723] LustreError: 371981:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 5580.794344] Lustre: Mounted lustre-client [ 5581.798949] Lustre: Unmounted lustre-client [ 5583.510796] Key type lgssc unregistered [ 5583.666594] LNet: 372337:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5584.674037] LNet: Removed LNI 192.168.201.5@tcp [ 5585.191924] Key type .llcrypt unregistered [ 5585.196948] Key type ._llcrypt unregistered [ 5585.706944] alg: No test for adler32 (adler32-zlib) [ 5586.469483] Key type ._llcrypt registered [ 5586.470803] Key type .llcrypt registered [ 5586.620530] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5586.881218] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5587.047131] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5587.049923] LNet: Accept secure, port 988 [ 5588.706191] Key type lgssc registered [ 5589.597729] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5597.947561] Lustre: DEBUG MARKER: Iteration 18 [ 5598.165951] LustreError: 373133:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5598.166328] LustreError: 373132:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5598.182588] LustreError: 373133:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [ 5599.410813] Lustre: Mounted lustre-client [ 5600.582251] Lustre: Unmounted lustre-client [ 5602.236616] Key type lgssc unregistered [ 5602.382568] LNet: 373485:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5603.425076] LNet: Removed LNI 192.168.201.5@tcp [ 5603.847796] Key type .llcrypt unregistered [ 5603.850594] Key type ._llcrypt unregistered [ 5604.353561] alg: No test for adler32 (adler32-zlib) [ 5605.106440] Key type ._llcrypt registered [ 5605.107836] Key type .llcrypt registered [ 5605.244054] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5605.448228] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5605.577075] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5605.579676] LNet: Accept secure, port 988 [ 5607.200191] Key type lgssc registered [ 5607.868523] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5614.744688] Lustre: DEBUG MARKER: Iteration 19 [ 5615.001501] LustreError: 374276:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5615.003815] LustreError: 374279:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5615.012130] LustreError: 374276:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 5616.217269] Lustre: Mounted lustre-client [ 5616.222048] Lustre: Skipped 1 previous similar message [ 5617.361640] Lustre: Unmounted lustre-client [ 5619.193127] Key type lgssc unregistered [ 5619.347781] LNet: 374630:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5620.385598] LNet: Removed LNI 192.168.201.5@tcp [ 5620.873919] Key type .llcrypt unregistered [ 5620.875593] Key type ._llcrypt unregistered [ 5621.419674] alg: No test for adler32 (adler32-zlib) [ 5622.176632] Key type ._llcrypt registered [ 5622.179119] Key type .llcrypt registered [ 5622.336928] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5622.583669] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5622.701341] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5622.704533] LNet: Accept secure, port 988 [ 5624.320521] Key type lgssc registered [ 5625.050357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5632.855925] Lustre: DEBUG MARKER: Iteration 20 [ 5633.045246] LustreError: 375424:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5633.045417] LustreError: 375422:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5633.057690] LustreError: 375424:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 5634.246795] Lustre: Mounted lustre-client [ 5635.394122] Lustre: Unmounted lustre-client [ 5637.383253] Key type lgssc unregistered [ 5637.531554] LNet: 375772:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5638.565109] LNet: Removed LNI 192.168.201.5@tcp [ 5639.063114] Key type .llcrypt unregistered [ 5639.064683] Key type ._llcrypt unregistered [ 5639.801718] alg: No test for adler32 (adler32-zlib) [ 5640.559431] Key type ._llcrypt registered [ 5640.561374] Key type .llcrypt registered [ 5640.683558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5640.902660] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5641.115158] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5641.119369] LNet: Accept secure, port 988 [ 5642.776915] Key type lgssc registered [ 5643.526382] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5650.610521] Lustre: DEBUG MARKER: Iteration 21 [ 5650.878875] LustreError: 376567:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5650.881116] LustreError: 376568:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5650.895101] LustreError: 376567:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [ 5652.097337] Lustre: Mounted lustre-client [ 5652.102950] Lustre: Skipped 1 previous similar message [ 5653.274280] Lustre: Unmounted lustre-client [ 5655.067333] Key type lgssc unregistered [ 5655.261511] LNet: 376918:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5656.292519] LNet: Removed LNI 192.168.201.5@tcp [ 5656.772921] Key type .llcrypt unregistered [ 5656.774637] Key type ._llcrypt unregistered [ 5657.338215] alg: No test for adler32 (adler32-zlib) [ 5658.095480] Key type ._llcrypt registered [ 5658.097663] Key type .llcrypt registered [ 5658.272949] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5658.522448] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5658.648256] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5658.653343] LNet: Accept secure, port 988 [ 5660.288165] Key type lgssc registered [ 5661.370092] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5670.203183] Lustre: DEBUG MARKER: Iteration 22 [ 5670.490386] LustreError: 377711:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5670.492392] LustreError: 377712:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5670.503746] LustreError: 377711:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5671.765051] Lustre: Mounted lustre-client [ 5673.012356] Lustre: Unmounted lustre-client [ 5675.332735] Key type lgssc unregistered [ 5675.648140] LNet: 378063:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5676.707544] LNet: Removed LNI 192.168.201.5@tcp [ 5677.239381] Key type .llcrypt unregistered [ 5677.243065] Key type ._llcrypt unregistered [ 5678.183961] alg: No test for adler32 (adler32-zlib) [ 5678.944504] Key type ._llcrypt registered [ 5678.946545] Key type .llcrypt registered [ 5679.099451] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5679.469919] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5679.695179] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5679.704204] LNet: Accept secure, port 988 [ 5681.360695] Key type lgssc registered [ 5682.607565] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5691.684530] Lustre: DEBUG MARKER: Iteration 23 [ 5692.037291] LustreError: 378857:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5692.042134] LustreError: 378858:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5692.047607] LustreError: 378857:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5693.249170] Lustre: Mounted lustre-client [ 5694.460171] Lustre: Unmounted lustre-client [ 5696.768221] Key type lgssc unregistered [ 5696.943117] LNet: 379208:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5697.959955] LNet: Removed LNI 192.168.201.5@tcp [ 5698.562794] Key type .llcrypt unregistered [ 5698.565548] Key type ._llcrypt unregistered [ 5699.157830] alg: No test for adler32 (adler32-zlib) [ 5699.936355] Key type ._llcrypt registered [ 5699.938506] Key type .llcrypt registered [ 5700.127350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5700.351169] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5700.557498] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5700.562381] LNet: Accept secure, port 988 [ 5702.224413] Key type lgssc registered [ 5703.149665] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5712.081654] Lustre: DEBUG MARKER: Iteration 24 [ 5712.433775] LustreError: 380002:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5712.433807] LustreError: 380003:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5712.445751] LustreError: 380003:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [ 5713.703932] Lustre: Mounted lustre-client [ 5714.923812] Lustre: Unmounted lustre-client [ 5717.583823] Key type lgssc unregistered [ 5717.849984] LNet: 380353:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5718.881754] LNet: Removed LNI 192.168.201.5@tcp [ 5719.569326] Key type .llcrypt unregistered [ 5719.570730] Key type ._llcrypt unregistered [ 5720.418915] alg: No test for adler32 (adler32-zlib) [ 5721.197651] Key type ._llcrypt registered [ 5721.200164] Key type .llcrypt registered [ 5721.364396] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5721.645361] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5721.808891] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5721.813537] LNet: Accept secure, port 988 [ 5723.464361] Key type lgssc registered [ 5724.232800] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5732.024861] Lustre: DEBUG MARKER: Iteration 25 [ 5732.203210] LustreError: 381147:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5732.205153] LustreError: 381146:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5732.218340] LustreError: 381147:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 5733.374663] Lustre: Mounted lustre-client [ 5734.436137] Lustre: Unmounted lustre-client [ 5736.566211] Key type lgssc unregistered [ 5736.742352] LNet: 381496:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5737.763233] LNet: Removed LNI 192.168.201.5@tcp [ 5738.258623] Key type .llcrypt unregistered [ 5738.260828] Key type ._llcrypt unregistered [ 5738.819701] alg: No test for adler32 (adler32-zlib) [ 5739.576937] Key type ._llcrypt registered [ 5739.579278] Key type .llcrypt registered [ 5739.777325] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5740.002552] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5740.225616] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5740.231878] LNet: Accept secure, port 988 [ 5741.896478] Key type lgssc registered [ 5742.753480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5750.504814] Lustre: DEBUG MARKER: Iteration 26 [ 5750.886273] LustreError: 382290:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5750.886292] LustreError: 382291:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5750.900719] LustreError: 382290:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [ 5752.181253] Lustre: Mounted lustre-client [ 5752.187088] Lustre: Skipped 1 previous similar message [ 5753.139191] Lustre: Unmounted lustre-client [ 5754.747917] Key type lgssc unregistered [ 5754.936307] LNet: 382645:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5756.007900] LNet: Removed LNI 192.168.201.5@tcp [ 5756.478389] Key type .llcrypt unregistered [ 5756.481775] Key type ._llcrypt unregistered [ 5757.111451] alg: No test for adler32 (adler32-zlib) [ 5757.883525] Key type ._llcrypt registered [ 5757.886950] Key type .llcrypt registered [ 5758.039508] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5758.315316] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5758.484893] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5758.488572] LNet: Accept secure, port 988 [ 5760.136159] Key type lgssc registered [ 5761.070289] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5768.612740] Lustre: DEBUG MARKER: Iteration 27 [ 5768.842713] LustreError: 383439:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5768.843353] LustreError: 383440:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5768.858614] LustreError: 383439:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 5770.075603] Lustre: Mounted lustre-client [ 5771.341820] Lustre: Unmounted lustre-client [ 5772.979665] Key type lgssc unregistered [ 5773.162429] LNet: 383790:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5774.178292] LNet: Removed LNI 192.168.201.5@tcp [ 5774.677804] Key type .llcrypt unregistered [ 5774.679731] Key type ._llcrypt unregistered [ 5775.315996] alg: No test for adler32 (adler32-zlib) [ 5776.070425] Key type ._llcrypt registered [ 5776.072300] Key type .llcrypt registered [ 5776.198541] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5776.377322] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5776.505831] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5776.508620] LNet: Accept secure, port 988 [ 5778.120075] Key type lgssc registered [ 5778.957853] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5787.243757] Lustre: DEBUG MARKER: Iteration 28 [ 5787.521337] LustreError: 384580:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5787.522821] LustreError: 384587:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5787.549732] LustreError: 384580:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4981 [ 5788.801481] Lustre: Mounted lustre-client [ 5789.952454] Lustre: Unmounted lustre-client [ 5791.711335] Key type lgssc unregistered [ 5791.838724] LNet: 384934:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5792.873277] LNet: Removed LNI 192.168.201.5@tcp [ 5793.348105] Key type .llcrypt unregistered [ 5793.349659] Key type ._llcrypt unregistered [ 5794.131730] alg: No test for adler32 (adler32-zlib) [ 5794.922151] Key type ._llcrypt registered [ 5794.924378] Key type .llcrypt registered [ 5795.092171] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5795.316805] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5795.466296] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5795.469519] LNet: Accept secure, port 988 [ 5797.105073] Key type lgssc registered [ 5797.845257] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5804.730430] Lustre: DEBUG MARKER: Iteration 29 [ 5804.994865] LustreError: 385728:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5805.003367] LustreError: 385729:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5805.007351] LustreError: 385728:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [ 5806.237046] Lustre: Mounted lustre-client [ 5807.250963] Lustre: Unmounted lustre-client [ 5809.025453] Key type lgssc unregistered [ 5809.155386] LNet: 386081:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5810.212598] LNet: Removed LNI 192.168.201.5@tcp [ 5810.660749] Key type .llcrypt unregistered [ 5810.664594] Key type ._llcrypt unregistered [ 5811.233072] alg: No test for adler32 (adler32-zlib) [ 5811.996509] Key type ._llcrypt registered [ 5811.998607] Key type .llcrypt registered [ 5812.117934] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5812.293639] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5812.430581] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5812.433633] LNet: Accept secure, port 988 [ 5814.050953] Key type lgssc registered [ 5814.892575] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5822.224545] Lustre: DEBUG MARKER: Iteration 30 [ 5822.531300] LustreError: 386874:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5822.538226] LustreError: 386875:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5822.553436] LustreError: 386874:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4988 [ 5823.770359] Lustre: Mounted lustre-client [ 5825.054024] Lustre: Unmounted lustre-client [ 5825.058286] Lustre: Skipped 1 previous similar message [ 5826.803876] Key type lgssc unregistered [ 5826.946574] LNet: 387226:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5828.004310] LNet: Removed LNI 192.168.201.5@tcp [ 5828.483041] Key type .llcrypt unregistered [ 5828.485018] Key type ._llcrypt unregistered [ 5829.032869] alg: No test for adler32 (adler32-zlib) [ 5829.787378] Key type ._llcrypt registered [ 5829.788752] Key type .llcrypt registered [ 5829.920437] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5830.149758] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5830.330112] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5830.332829] LNet: Accept secure, port 988 [ 5831.968126] Key type lgssc registered [ 5832.768495] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5840.865207] Lustre: DEBUG MARKER: Iteration 31 [ 5841.108912] LustreError: 388016:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5841.132423] LustreError: 388034:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5841.138818] LustreError: 388016:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4981 [ 5842.332456] Lustre: Mounted lustre-client [ 5842.336068] Lustre: Skipped 1 previous similar message [ 5843.456028] Lustre: Unmounted lustre-client [ 5845.427843] Key type lgssc unregistered [ 5845.564651] LNet: 388373:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5846.625086] LNet: Removed LNI 192.168.201.5@tcp [ 5847.075271] Key type .llcrypt unregistered [ 5847.076790] Key type ._llcrypt unregistered [ 5847.757076] alg: No test for adler32 (adler32-zlib) [ 5848.516723] Key type ._llcrypt registered [ 5848.520211] Key type .llcrypt registered [ 5848.673764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5848.867084] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5848.999768] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5849.001305] LNet: Accept secure, port 988 [ 5850.608127] Key type lgssc registered [ 5851.412511] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5858.975679] Lustre: DEBUG MARKER: Iteration 32 [ 5859.199487] LustreError: 389161:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5859.200445] LustreError: 389169:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5859.215102] LustreError: 389161:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4986 [ 5860.473682] Lustre: Mounted lustre-client [ 5861.719269] Lustre: Unmounted lustre-client [ 5863.556139] Key type lgssc unregistered [ 5863.717798] LNet: 389516:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5864.741116] LNet: Removed LNI 192.168.201.5@tcp [ 5865.234420] Key type .llcrypt unregistered [ 5865.237575] Key type ._llcrypt unregistered [ 5865.735675] alg: No test for adler32 (adler32-zlib) [ 5866.489332] Key type ._llcrypt registered [ 5866.491051] Key type .llcrypt registered [ 5866.631827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5866.832439] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5867.016767] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5867.020794] LNet: Accept secure, port 988 [ 5868.657864] Key type lgssc registered [ 5869.446427] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5876.655421] Lustre: DEBUG MARKER: Iteration 33 [ 5876.847948] LustreError: 390310:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5876.849035] LustreError: 390311:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5876.862264] LustreError: 390310:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 5878.016731] Lustre: Mounted lustre-client [ 5879.084134] Lustre: Unmounted lustre-client [ 5880.826709] Key type lgssc unregistered [ 5881.004733] LNet: 390664:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5882.018727] LNet: Removed LNI 192.168.201.5@tcp [ 5882.429665] Key type .llcrypt unregistered [ 5882.431420] Key type ._llcrypt unregistered [ 5883.070465] alg: No test for adler32 (adler32-zlib) [ 5883.823929] Key type ._llcrypt registered [ 5883.825943] Key type .llcrypt registered [ 5883.996089] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5884.229305] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5884.373131] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5884.376669] LNet: Accept secure, port 988 [ 5886.000125] Key type lgssc registered [ 5886.774846] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5893.779321] Lustre: DEBUG MARKER: Iteration 34 [ 5893.985259] LustreError: 391457:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5893.985873] LustreError: 391458:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5893.996408] LustreError: 391457:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5895.143125] Lustre: Mounted lustre-client [ 5896.335380] Lustre: Unmounted lustre-client [ 5898.005145] Key type lgssc unregistered [ 5898.142576] LNet: 391807:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5899.171437] LNet: Removed LNI 192.168.201.5@tcp [ 5899.578746] Key type .llcrypt unregistered [ 5899.580653] Key type ._llcrypt unregistered [ 5900.102083] alg: No test for adler32 (adler32-zlib) [ 5900.858414] Key type ._llcrypt registered [ 5900.860412] Key type .llcrypt registered [ 5901.010769] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5901.229689] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5901.356734] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5901.359146] LNet: Accept secure, port 988 [ 5902.986470] Key type lgssc registered [ 5903.778409] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5910.988659] Lustre: DEBUG MARKER: Iteration 35 [ 5911.241179] LustreError: 392600:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5911.242266] LustreError: 392604:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5911.254307] LustreError: 392600:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4989 [ 5912.488309] Lustre: Mounted lustre-client [ 5913.652124] Lustre: Unmounted lustre-client [ 5915.407919] Key type lgssc unregistered [ 5915.562350] LNet: 392953:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5916.579242] LNet: Removed LNI 192.168.201.5@tcp [ 5917.081816] Key type .llcrypt unregistered [ 5917.083313] Key type ._llcrypt unregistered [ 5917.597348] alg: No test for adler32 (adler32-zlib) [ 5918.356551] Key type ._llcrypt registered [ 5918.358616] Key type .llcrypt registered [ 5918.515667] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5918.799875] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5919.016883] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5919.023842] LNet: Accept secure, port 988 [ 5920.664327] Key type lgssc registered [ 5921.451775] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5929.135885] Lustre: DEBUG MARKER: Iteration 36 [ 5929.328434] LustreError: 393747:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5929.329813] LustreError: 393748:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5929.344820] LustreError: 393747:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [ 5930.480312] Lustre: Mounted lustre-client [ 5931.709943] Lustre: Unmounted lustre-client [ 5933.295722] Key type lgssc unregistered [ 5933.463247] LNet: 394094:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5934.500114] LNet: Removed LNI 192.168.201.5@tcp [ 5935.050739] Key type .llcrypt unregistered [ 5935.052106] Key type ._llcrypt unregistered [ 5935.700296] alg: No test for adler32 (adler32-zlib) [ 5936.457477] Key type ._llcrypt registered [ 5936.459291] Key type .llcrypt registered [ 5936.615042] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5936.835889] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5937.001765] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5937.006339] LNet: Accept secure, port 988 [ 5938.648367] Key type lgssc registered [ 5939.701322] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5947.598302] Lustre: DEBUG MARKER: Iteration 37 [ 5947.816983] LustreError: 394887:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5947.819093] LustreError: 394889:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5947.827033] LustreError: 394887:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 5948.985629] Lustre: Mounted lustre-client [ 5948.989305] Lustre: Skipped 1 previous similar message [ 5950.064066] Lustre: Unmounted lustre-client [ 5951.907982] Key type lgssc unregistered [ 5952.065382] LNet: 395243:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5953.125479] LNet: Removed LNI 192.168.201.5@tcp [ 5953.563493] Key type .llcrypt unregistered [ 5953.566957] Key type ._llcrypt unregistered [ 5953.938665] alg: No test for adler32 (adler32-zlib) [ 5954.691412] Key type ._llcrypt registered [ 5954.694899] Key type .llcrypt registered [ 5954.877283] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5955.118787] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5955.283722] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5955.286761] LNet: Accept secure, port 988 [ 5956.920133] Key type lgssc registered [ 5957.734806] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5964.854554] Lustre: DEBUG MARKER: Iteration 38 [ 5965.040461] LustreError: 396038:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5965.040810] LustreError: 396039:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5965.050688] LustreError: 396038:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5966.228537] Lustre: Mounted lustre-client [ 5967.070188] Lustre: Unmounted lustre-client [ 5968.763119] Key type lgssc unregistered [ 5968.953267] LNet: 396389:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5968.960307] LNet: Removed LNI 192.168.201.5@tcp [ 5969.374916] Key type .llcrypt unregistered [ 5969.376162] Key type ._llcrypt unregistered [ 5970.026784] alg: No test for adler32 (adler32-zlib) [ 5970.779786] Key type ._llcrypt registered [ 5970.781859] Key type .llcrypt registered [ 5970.953231] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5971.206251] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5971.389961] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5971.393703] LNet: Accept secure, port 988 [ 5973.024115] Key type lgssc registered [ 5973.560977] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5980.455683] Lustre: DEBUG MARKER: Iteration 39 [ 5980.828747] LustreError: 397181:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5980.830093] LustreError: 397182:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5980.840237] LustreError: 397181:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 5982.034158] Lustre: Mounted lustre-client [ 5983.154116] Lustre: Unmounted lustre-client [ 5985.333176] Key type lgssc unregistered [ 5985.536085] LNet: 397531:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5986.594792] LNet: Removed LNI 192.168.201.5@tcp [ 5987.122748] Key type .llcrypt unregistered [ 5987.124124] Key type ._llcrypt unregistered [ 5987.625160] alg: No test for adler32 (adler32-zlib) [ 5988.439681] Key type ._llcrypt registered [ 5988.441332] Key type .llcrypt registered [ 5988.607655] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5988.848953] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 5989.032408] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 5989.035420] LNet: Accept secure, port 988 [ 5990.664270] Key type lgssc registered [ 5991.432572] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5999.365257] Lustre: DEBUG MARKER: Iteration 40 [ 5999.603538] LustreError: 398325:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5999.613788] LustreError: 398331:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5999.620395] LustreError: 398325:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4984 [ 6000.771087] Lustre: Mounted lustre-client [ 6000.774660] Lustre: Skipped 1 previous similar message [ 6001.724361] Lustre: Unmounted lustre-client [ 6003.188370] Key type lgssc unregistered [ 6003.350540] LNet: 398675:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6004.391994] LNet: Removed LNI 192.168.201.5@tcp [ 6004.751295] Key type .llcrypt unregistered [ 6004.755564] Key type ._llcrypt unregistered [ 6005.105653] alg: No test for adler32 (adler32-zlib) [ 6005.895374] Key type ._llcrypt registered [ 6005.897141] Key type .llcrypt registered [ 6006.023401] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6006.196019] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 6006.330192] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 6006.334306] LNet: Accept secure, port 988 [ 6007.952145] Key type lgssc registered [ 6008.647237] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6015.754785] Lustre: DEBUG MARKER: Iteration 41 [ 6015.994664] LustreError: 399469:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6015.995091] LustreError: 399466:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6016.002525] LustreError: 399469:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6017.165413] Lustre: Mounted lustre-client [ 6017.167536] Lustre: Skipped 1 previous similar message [ 6018.079509] Lustre: Unmounted lustre-client [ 6019.472982] Key type lgssc unregistered [ 6019.650762] LNet: 399818:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6020.705239] LNet: Removed LNI 192.168.201.5@tcp [ 6021.135491] Key type .llcrypt unregistered [ 6021.138128] Key type ._llcrypt unregistered [ 6021.630276] alg: No test for adler32 (adler32-zlib) [ 6022.385895] Key type ._llcrypt registered [ 6022.390653] Key type .llcrypt registered [ 6022.528919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6022.744334] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 6022.880913] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 6022.883794] LNet: Accept secure, port 988 [ 6024.536115] Key type lgssc registered [ 6025.112298] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6031.517135] Lustre: DEBUG MARKER: Iteration 42 [ 6031.738018] LustreError: 400612:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6031.741151] LustreError: 400611:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6031.750752] LustreError: 400612:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 6032.903948] Lustre: Mounted lustre-client [ 6034.074525] Lustre: Unmounted lustre-client [ 6035.739259] Key type lgssc unregistered [ 6035.894925] LNet: 400961:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6036.963606] LNet: Removed LNI 192.168.201.5@tcp [ 6037.379903] Key type .llcrypt unregistered [ 6037.382673] Key type ._llcrypt unregistered [ 6037.951049] alg: No test for adler32 (adler32-zlib) [ 6038.722419] Key type ._llcrypt registered [ 6038.724200] Key type .llcrypt registered [ 6038.853244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6039.081899] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 6039.220280] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 6039.226620] LNet: Accept secure, port 988 [ 6040.849225] Key type lgssc registered [ 6041.709458] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6048.283528] Lustre: DEBUG MARKER: Iteration 43 [ 6048.447157] LustreError: 401756:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6048.448079] LustreError: 401757:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6048.454819] LustreError: 401756:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6049.607766] Lustre: Mounted lustre-client [ 6050.613933] Lustre: Unmounted lustre-client [ 6052.273499] Key type lgssc unregistered [ 6052.389547] LNet: 402108:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6053.410764] LNet: Removed LNI 192.168.201.5@tcp [ 6053.894916] Key type .llcrypt unregistered [ 6053.897345] Key type ._llcrypt unregistered [ 6054.404048] alg: No test for adler32 (adler32-zlib) [ 6055.168282] Key type ._llcrypt registered [ 6055.171226] Key type .llcrypt registered [ 6055.294604] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6055.537264] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 6055.668760] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 6055.671530] LNet: Accept secure, port 988 [ 6057.328155] Key type lgssc registered [ 6057.889398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6064.665444] Lustre: DEBUG MARKER: Iteration 44 [ 6064.896914] LustreError: 402901:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6064.899218] LustreError: 402902:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6064.908482] LustreError: 402901:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [ 6066.069554] Lustre: Mounted lustre-client [ 6067.118456] Lustre: Unmounted lustre-client [ 6068.664774] Key type lgssc unregistered [ 6068.822519] LNet: 403254:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6069.857290] LNet: Removed LNI 192.168.201.5@tcp [ 6070.292737] Key type .llcrypt unregistered [ 6070.294614] Key type ._llcrypt unregistered [ 6070.932824] alg: No test for adler32 (adler32-zlib) [ 6071.701416] Key type ._llcrypt registered [ 6071.703538] Key type .llcrypt registered [ 6071.834692] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6072.011312] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 6072.146393] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 6072.149637] LNet: Accept secure, port 988 [ 6073.776124] Key type lgssc registered [ 6074.560901] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6081.160857] Lustre: DEBUG MARKER: Iteration 45 [ 6081.394799] LustreError: 404047:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6081.399159] LustreError: 404048:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6081.405799] LustreError: 404047:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 6082.571743] Lustre: Mounted lustre-client [ 6083.754141] Lustre: Unmounted lustre-client [ 6085.457853] Key type lgssc unregistered [ 6085.617586] LNet: 404396:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6086.627187] LNet: Removed LNI 192.168.201.5@tcp [ 6087.043341] Key type .llcrypt unregistered [ 6087.046041] Key type ._llcrypt unregistered [ 6087.512593] alg: No test for adler32 (adler32-zlib) [ 6088.268593] Key type ._llcrypt registered [ 6088.273412] Key type .llcrypt registered [ 6088.425853] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6088.611272] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 6088.729994] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 6088.732306] LNet: Accept secure, port 988 [ 6090.360128] Key type lgssc registered [ 6090.913390] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6097.906748] Lustre: DEBUG MARKER: Iteration 46 [ 6098.093193] LustreError: 405190:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6098.093982] LustreError: 405191:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6098.103815] LustreError: 405190:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 6099.265043] Lustre: Mounted lustre-client [ 6100.107039] Lustre: Unmounted lustre-client [ 6101.786342] Key type lgssc unregistered [ 6101.919219] LNet: 405543:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6102.945291] LNet: Removed LNI 192.168.201.5@tcp [ 6103.382027] Key type .llcrypt unregistered [ 6103.383815] Key type ._llcrypt unregistered [ 6103.971896] alg: No test for adler32 (adler32-zlib) [ 6104.729380] Key type ._llcrypt registered [ 6104.731610] Key type .llcrypt registered [ 6104.842340] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6104.985960] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 6105.127194] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 6105.130610] LNet: Accept secure, port 988 [ 6106.744358] Key type lgssc registered [ 6107.398268] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6114.377200] Lustre: DEBUG MARKER: Iteration 47 [ 6114.547581] LustreError: 406338:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6114.549751] LustreError: 406336:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6114.554433] LustreError: 406338:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 6115.695653] Lustre: Mounted lustre-client [ 6116.450132] Lustre: Unmounted lustre-client [ 6117.857359] Key type lgssc unregistered [ 6118.005214] LNet: 406687:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6119.074496] LNet: Removed LNI 192.168.201.5@tcp [ 6119.496045] Key type .llcrypt unregistered [ 6119.497854] Key type ._llcrypt unregistered [ 6119.989689] alg: No test for adler32 (adler32-zlib) [ 6120.742446] Key type ._llcrypt registered [ 6120.746338] Key type .llcrypt registered [ 6120.950596] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6121.207798] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 6121.399975] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 6121.403808] LNet: Accept secure, port 988 [ 6123.080094] Key type lgssc registered [ 6123.700444] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6130.917916] Lustre: DEBUG MARKER: Iteration 48 [ 6131.169320] LustreError: 407480:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6131.170061] LustreError: 407481:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6131.180135] LustreError: 407480:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 6132.335805] Lustre: Mounted lustre-client [ 6132.996111] Lustre: Unmounted lustre-client [ 6134.504641] Key type lgssc unregistered [ 6134.641272] LNet: 407832:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6135.649541] LNet: Removed LNI 192.168.201.5@tcp [ 6136.034468] Key type .llcrypt unregistered [ 6136.036390] Key type ._llcrypt unregistered [ 6136.633333] alg: No test for adler32 (adler32-zlib) [ 6137.386750] Key type ._llcrypt registered [ 6137.390663] Key type .llcrypt registered [ 6137.520570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6137.726862] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 6137.861258] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 6137.863737] LNet: Accept secure, port 988 [ 6139.480288] Key type lgssc registered [ 6140.237077] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6147.607779] Lustre: DEBUG MARKER: Iteration 49 [ 6147.851171] LustreError: 408625:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6147.857051] LustreError: 408630:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6147.860467] LustreError: 408625:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 6149.044168] Lustre: Mounted lustre-client [ 6149.999034] Lustre: Unmounted lustre-client [ 6151.702508] Key type lgssc unregistered [ 6151.863442] LNet: 408981:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6152.934597] LNet: Removed LNI 192.168.201.5@tcp [ 6153.271903] Key type .llcrypt unregistered [ 6153.273091] Key type ._llcrypt unregistered [ 6153.703056] alg: No test for adler32 (adler32-zlib) [ 6154.455351] Key type ._llcrypt registered [ 6154.456961] Key type .llcrypt registered [ 6154.578503] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6154.752108] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 6154.875421] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 6154.878310] LNet: Accept secure, port 988 [ 6156.504159] Key type lgssc registered [ 6157.096270] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6163.284776] Lustre: DEBUG MARKER: Iteration 50 [ 6163.492331] LustreError: 409774:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6163.492754] LustreError: 409775:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6163.504268] LustreError: 409774:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [ 6164.679429] Lustre: Mounted lustre-client [ 6164.687128] Lustre: Skipped 1 previous similar message [ 6165.554517] Lustre: Unmounted lustre-client [ 6167.143974] Key type lgssc unregistered [ 6167.298726] LNet: 410123:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6168.357037] LNet: Removed LNI 192.168.201.5@tcp [ 6168.735435] Key type .llcrypt unregistered [ 6168.737021] Key type ._llcrypt unregistered [ 6169.148827] alg: No test for adler32 (adler32-zlib) [ 6169.907449] Key type ._llcrypt registered [ 6169.909344] Key type .llcrypt registered [ 6170.049383] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6170.248085] Lustre: Lustre: Build Version: 2.15.8_5_gc131e65 [ 6170.372606] LNet: Added LNI 192.168.201.5@tcp [8/256/0/180] [ 6170.375500] LNet: Accept secure, port 988 [ 6171.994658] Key type lgssc registered [ 6172.811251] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6180.856729] Lustre: Mounted lustre-client [ 6185.288636] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 20:48:21 (1781052501) [ 6193.120239] Lustre: 411432:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781052502/real 1781052502] req@00000000a2d2a60e x1867568891565056/t0(0) o36->lustre-MDT0000-mdc-ffff9afb980d7800@192.168.201.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1781052509 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'ln.0' [ 6193.153315] Lustre: lustre-MDT0000-mdc-ffff9afb980d7800: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6193.175400] Lustre: lustre-MDT0000-mdc-ffff9afb980d7800: Connection restored to (at 192.168.201.105@tcp) [ 6200.289172] Lustre: 411432:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781052509/real 1781052509] req@00000000a2d2a60e x1867568891565056/t0(0) o36->lustre-MDT0000-mdc-ffff9afb980d7800@192.168.201.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1781052516 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 6200.313908] Lustre: lustre-MDT0000-mdc-ffff9afb980d7800: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6200.340393] Lustre: lustre-MDT0000-mdc-ffff9afb980d7800: Connection restored to (at 192.168.201.105@tcp) [ 6207.456752] Lustre: 411432:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781052516/real 1781052516] req@00000000a2d2a60e x1867568891565056/t0(0) o36->lustre-MDT0000-mdc-ffff9afb980d7800@192.168.201.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1781052523 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 6207.476046] Lustre: lustre-MDT0000-mdc-ffff9afb980d7800: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6207.500620] Lustre: lustre-MDT0000-mdc-ffff9afb980d7800: Connection restored to (at 192.168.201.105@tcp) [ 6214.624800] Lustre: 411432:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781052524/real 1781052524] req@00000000a2d2a60e x1867568891565056/t0(0) o36->lustre-MDT0000-mdc-ffff9afb980d7800@192.168.201.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1781052531 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 6214.644212] Lustre: lustre-MDT0000-mdc-ffff9afb980d7800: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6214.667733] Lustre: lustre-MDT0000-mdc-ffff9afb980d7800: Connection restored to (at 192.168.201.105@tcp) [ 6221.792309] Lustre: 411432:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1781052531/real 1781052531] req@00000000a2d2a60e x1867568891565056/t0(0) o36->lustre-MDT0000-mdc-ffff9afb980d7800@192.168.201.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1781052538 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 6221.807996] Lustre: lustre-MDT0000-mdc-ffff9afb980d7800: Connection to lustre-MDT0000 (at 192.168.201.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6221.838461] Lustre: lustre-MDT0000-mdc-ffff9afb980d7800: Connection restored to (at 192.168.201.105@tcp) [ 6224.527244] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 20:49:00 (1781052540) [ 6231.943577] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 20:49:07 (1781052547) [ 6239.460460] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 20:49:14 (1781052554) [ 6243.429930] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 6244.798087] Lustre: DEBUG MARKER: == sanityn test complete, duration 6080 sec ============== 20:49:20 (1781052560) [ 6384.936191] Lustre: Unmounted lustre-client [ 6425.762780] Key type lgssc unregistered [ 6425.914618] LNet: 414705:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6426.981198] LNet: Removed LNI 192.168.201.5@tcp [ 6427.413923] Key type .llcrypt unregistered [ 6427.421075] Key type ._llcrypt unregistered