[ 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.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 413712833 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 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 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.001014] APIC: Switch to symmetric I/O mode setup [ 0.002426] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004009] kvm-guest: setup PV IPIs [ 0.007000] ..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.007019] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008011] pid_max: default: 32768 minimum: 301 [ 0.009180] LSM: Security Framework initializing [ 0.010040] Yama: becoming mindful. [ 0.011030] SELinux: Initializing. [ 0.012067] *** VALIDATE selinux *** [ 0.020849] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024823] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025196] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026155] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027166] *** VALIDATE tmpfs *** [ 0.029310] *** VALIDATE proc *** [ 0.031039] *** VALIDATE cgroup *** [ 0.032007] *** VALIDATE cgroup2 *** [ 0.034231] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036039] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037005] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038027] Spectre V2 : User space: Vulnerable [ 0.039005] Speculative Store Bypass: Vulnerable [ 0.042880] debug: unmapping init [mem 0xffffffffb4059000-0xffffffffb4060fff] [ 0.045134] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046629] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047014] ... version: 2 [ 0.048007] ... bit width: 48 [ 0.049007] ... generic registers: 4 [ 0.050008] ... value mask: 0000ffffffffffff [ 0.051008] ... max period: 00007fffffffffff [ 0.052009] ... fixed-purpose events: 3 [ 0.053007] ... event mask: 000000070000000f [ 0.055224] rcu: Hierarchical SRCU implementation. [ 0.057566] smp: Bringing up secondary CPUs ... [ 0.058568] x86: Booting SMP configuration: [ 0.059014] .... node #0, CPUs: #1 #2 #3 [ 0.062343] smp: Brought up 1 node, 4 CPUs [ 0.064009] smpboot: Max logical packages: 1 [ 0.065017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.238028] node 0 deferred pages initialised in 172ms [ 0.241126] devtmpfs: initialized [ 0.242347] x86/mm: Memory block size: 128MB [ 0.245818] gcov: version magic: 0x41383552 [ 0.247680] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.250087] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.252238] pinctrl core: initialized pinctrl subsystem [ 0.254111] [ 0.254609] ************************************************************* [ 0.257010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.259009] ** ** [ 0.261008] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.262007] ** ** [ 0.264010] ** This means that this kernel is built to expose internal ** [ 0.266007] ** IOMMU data structures, which may compromise security on ** [ 0.268011] ** your system. ** [ 0.270010] ** ** [ 0.272010] ** If you see this message and you are not debugging the ** [ 0.274010] ** kernel, report this immediately to your vendor! ** [ 0.276007] ** ** [ 0.278009] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.280010] ************************************************************* [ 0.282503] NET: Registered protocol family 16 [ 0.284296] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.286045] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.289047] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.292035] cpuidle: using governor menu [ 0.293464] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.296377] PCI: Using configuration type 1 for base access [ 0.298187] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.307106] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.308017] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.309127] cryptd: max_cpu_qlen set to 1000 [ 0.310293] ACPI: Added _OSI(Module Device) [ 0.311018] ACPI: Added _OSI(Processor Device) [ 0.312010] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.314009] ACPI: Added _OSI(Processor Aggregator Device) [ 0.319007] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.324582] ACPI: Interpreter enabled [ 0.326054] ACPI: PM: (supports S0 S3 S4 S5) [ 0.326942] ACPI: Using IOAPIC for interrupt routing [ 0.328088] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.330300] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.339377] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.340027] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.342009] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.344063] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.349247] acpiphp: Slot [2] registered [ 0.350125] acpiphp: Slot [3] registered [ 0.350905] acpiphp: Slot [4] registered [ 0.352044] acpiphp: Slot [5] registered [ 0.352937] acpiphp: Slot [6] registered [ 0.354096] acpiphp: Slot [7] registered [ 0.355074] acpiphp: Slot [8] registered [ 0.357069] acpiphp: Slot [9] registered [ 0.357948] acpiphp: Slot [10] registered [ 0.358045] acpiphp: Slot [11] registered [ 0.359029] acpiphp: Slot [12] registered [ 0.360065] acpiphp: Slot [13] registered [ 0.362066] acpiphp: Slot [14] registered [ 0.363093] acpiphp: Slot [15] registered [ 0.365064] acpiphp: Slot [16] registered [ 0.365887] acpiphp: Slot [17] registered [ 0.366044] acpiphp: Slot [18] registered [ 0.366974] acpiphp: Slot [19] registered [ 0.368066] acpiphp: Slot [20] registered [ 0.370066] acpiphp: Slot [21] registered [ 0.371092] acpiphp: Slot [22] registered [ 0.373050] acpiphp: Slot [23] registered [ 0.373904] acpiphp: Slot [24] registered [ 0.374045] acpiphp: Slot [25] registered [ 0.375067] acpiphp: Slot [26] registered [ 0.377048] acpiphp: Slot [27] registered [ 0.377956] acpiphp: Slot [28] registered [ 0.379049] acpiphp: Slot [29] registered [ 0.380063] acpiphp: Slot [30] registered [ 0.381000] acpiphp: Slot [31] registered [ 0.381000] PCI host bridge to bus 0000:00 [ 0.381000] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.384025] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.386044] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.389039] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.391022] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.394015] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.396224] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.400528] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.405612] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.411943] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.415685] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.419027] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.421014] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.424016] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.426561] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.428756] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.431026] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.434424] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.438013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.447016] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.451008] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.456970] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.462014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.467010] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.477010] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.485364] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.489010] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.494011] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.505014] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.514112] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.515832] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.517216] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.519251] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.521155] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.524222] iommu: Default domain type: Passthrough [ 0.526530] SCSI subsystem initialized [ 0.527106] ACPI: bus type USB registered [ 0.529104] usbcore: registered new interface driver usbfs [ 0.531053] usbcore: registered new interface driver hub [ 0.533050] usbcore: registered new device driver usb [ 0.535185] pps_core: LinuxPPS API ver. 1 registered [ 0.537009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.541050] PTP clock support registered [ 0.543094] EDAC MC: Ver: 3.0.0 [ 0.544340] PCI: Using ACPI for IRQ routing [ 0.545377] NetLabel: Initializing [ 0.546007] NetLabel: domain hash size = 128 [ 0.546925] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.548045] NetLabel: unlabeled traffic allowed by default [ 0.550090] vgaarb: loaded [ 0.551317] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.552006] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.560390] clocksource: Switched to clocksource kvm-clock [ 0.680080] VFS: Disk quotas dquot_6.6.0 [ 0.681651] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.684226] *** VALIDATE ramfs *** [ 0.685524] *** VALIDATE hugetlbfs *** [ 0.687135] pnp: PnP ACPI init [ 0.689672] pnp: PnP ACPI: found 6 devices [ 0.704700] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.706988] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.708247] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.709603] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.711164] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.712646] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.714722] NET: Registered protocol family 2 [ 0.716709] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.720166] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.722471] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.726668] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.729073] TCP: Hash tables configured (established 65536 bind 65536) [ 0.731437] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.733630] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.735455] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.738358] NET: Registered protocol family 1 [ 0.740089] RPC: Registered named UNIX socket transport module. [ 0.741308] RPC: Registered udp transport module. [ 0.742409] RPC: Registered tcp transport module. [ 0.743710] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.745826] NET: Registered protocol family 44 [ 0.747312] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.748558] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.749796] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.751705] PCI: CLS 0 bytes, default 64 [ 0.753573] Unpacking initramfs... [ 2.129786] debug: unmapping init [mem 0xffff8d517cc64000-0xffff8d517ffcffff] [ 2.133890] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.135140] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.136771] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.778021] Initialise system trusted keyrings [ 2.779709] Key type blacklist registered [ 2.781476] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.790705] zbud: loaded [ 2.793544] *** VALIDATE nfs *** [ 2.794753] *** VALIDATE nfs4 *** [ 2.796052] pstore: using deflate compression [ 2.798513] Platform Keyring initialized [ 2.881352] NET: Registered protocol family 38 [ 2.882985] Key type asymmetric registered [ 2.883928] Asymmetric key parser 'x509' registered [ 2.885019] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.886874] io scheduler mq-deadline registered [ 2.888259] io scheduler kyber registered [ 2.889851] io scheduler bfq registered [ 2.891445] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.893649] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.895390] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.897235] ACPI: Power Button [PWRF] [ 2.984977] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.095370] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.176671] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.210072] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.243230] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.249349] Non-volatile memory driver v1.3 [ 3.251044] Linux agpgart interface v0.103 [ 3.276087] virtio_blk virtio1: [vda] 67976 512-byte logical blocks (34.8 MB/33.2 MiB) [ 3.277897] vda: detected capacity change from 0 to 34803712 [ 3.290147] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.293225] vdb: detected capacity change from 0 to 1073741824 [ 3.298627] libphy: Fixed MDIO Bus: probed [ 3.302885] usbcore: registered new interface driver usbserial_generic [ 3.305133] usbserial: USB Serial support registered for generic [ 3.306703] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.310941] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.312209] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.313657] mousedev: PS/2 mouse device common for all mice [ 3.315497] rtc_cmos 00:05: RTC can wake from S4 [ 3.317860] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.318022] rtc_cmos 00:05: registered as rtc0 [ 3.321858] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.324412] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.325664] intel_pstate: CPU model not supported [ 3.329189] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.332455] hid: raw HID events driver (C) Jiri Kosina [ 3.333920] usbcore: registered new interface driver usbhid [ 3.335962] usbhid: USB HID core driver [ 3.337043] drop_monitor: Initializing network drop monitor service [ 3.338920] Initializing XFRM netlink socket [ 3.340981] NET: Registered protocol family 10 [ 3.343297] Segment Routing with IPv6 [ 3.344763] NET: Registered protocol family 17 [ 3.346328] mpls_gso: MPLS GSO support [ 3.351597] RAS: Correctable Errors collector initialized. [ 3.353975] AVX version of gcm_enc/dec engaged. [ 3.355853] AES CTR mode by8 optimization enabled [ 3.424367] sched_clock: Marking stable (3424342538, 0)->(4243242377, -818899839) [ 3.427516] registered taskstats version 1 [ 3.429867] Loading compiled-in X.509 certificates [ 3.431179] zswap: loaded using pool lzo/zbud [ 3.457611] Key type big_key registered [ 3.469517] Key type encrypted registered [ 3.470570] ima: No TPM chip found, activating TPM-bypass! [ 3.471960] ima: Allocated hash algorithm: sha1 [ 3.472994] ima: No architecture policies found [ 3.474433] evm: Initialising EVM extended attributes: [ 3.475983] evm: security.selinux [ 3.476666] evm: security.ima [ 3.477486] evm: security.capability [ 3.478354] evm: HMAC attrs: 0x1 [ 3.480823] rtc_cmos 00:05: setting system clock to 2025-10-22 08:14:38 UTC (1761120878) [ 3.485966] debug: unmapping init [mem 0xffffffffb5003000-0xffffffffb51fffff] [ 3.489342] debug: unmapping init [mem 0xffffffffb3d82000-0xffffffffb4058fff] [ 3.502117] Write protecting the kernel read-only data: 28672k [ 3.506337] debug: unmapping init [mem 0xffffffffb2403000-0xffffffffb25fffff] [ 3.509859] debug: unmapping init [mem 0xffffffffb2d14000-0xffffffffb2dfffff] [ 3.543251] 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.555713] systemd[1]: Detected virtualization kvm. [ 3.558470] systemd[1]: Detected architecture x86-64. [ 3.560933] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.584172] systemd[1]: No hostname configured. [ 3.586440] systemd[1]: Set hostname to . [ 3.589021] random: systemd: uninitialized urandom read (16 bytes read) [ 3.592246] systemd[1]: Initializing machine ID from random generator. [ 3.632650] random: ln: uninitialized urandom read (6 bytes read) [ 3.716114] random: systemd: uninitialized urandom read (16 bytes read) [ 3.718579] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.721552] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.725851] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket. [ OK ] Reached target Timers. [ OK ] Reached target Paths. Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Reached target Slices. Starting Setup Virtual Console... [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.239926] device-mapper: uevent: version 1.0.3 [ 4.241716] 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. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.859675] virtio_net virtio0 ens2: renamed from eth0 [ 4.915886] scsi host0: ata_piix [ 4.938494] scsi host1: ata_piix [ 4.940610] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.943802] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.725501] dracut-initqueue[587]: RTNETLINK answers: File exists [ 9.985661] random: crng init done [ 9.987055] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.704769] 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 target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.906549] printk: systemd: 25 output lines suppressed due to ratelimiting [ 12.163301] SELinux: Disabled at runtime. [ 12.226826] 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) [ 12.234302] systemd[1]: Detected virtualization kvm. [ 12.236050] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.739250] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.742385] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.747689] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.751194] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.754271] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.765720] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.772332] systemd[1]: Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Control Socket. Starting Remount Root and Kernel File Systems... [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-getty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting Kernel Debug File System... Starting Create list of required st…ce nodes for the current kernel... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Kernel Socket. [ 12.894665] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Mounting POSIX Message Queue File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Mounting Huge Pages File System... [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd File Systems. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 13.323378] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.699180] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.711122] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.971776] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.992815] EDAC sbridge: Ver: 1.1.2 [ 15.247580] Key type dns_resolver registered [ 15.546633] NFS: Registering the id_resolver key type [ 15.548421] Key type id_resolver registered [ 15.549822] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. [ OK ] Started Authorization Manager. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg201-client login: [ 36.731113] libcfs: loading out-of-tree module taints kernel. [ 36.798113] alg: No test for adler32 (adler32-zlib) [ 37.549322] Key type ._llcrypt registered [ 37.550239] Key type .llcrypt registered [ 37.682296] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 37.899902] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 38.168974] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 38.171183] LNet: Accept secure, port 988 [ 39.775110] Key type lgssc registered [ 40.285197] Lustre: Echo OBD driver; http://www.lustre.org/ [ 94.780529] Lustre: Mounted lustre-client [ 97.109090] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 108.987662] Lustre: DEBUG MARKER: oleg201-client.virtnet: executing check_logdir /tmp/testlogs/ [ 110.529595] Lustre: DEBUG MARKER: oleg201-client.virtnet: executing yml_node [ 112.020277] Lustre: DEBUG MARKER: Client: 2.15.7.10 [ 112.904812] Lustre: DEBUG MARKER: MDS: 2.15.7.10 [ 113.840891] Lustre: DEBUG MARKER: OSS: 2.15.7.10 [ 114.376705] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Wed Oct 22 04:16:28 EDT 2025 [ 117.117180] Lustre: DEBUG MARKER: excepting tests: 27 28 [ 117.682705] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 117.869941] Lustre: lustre-OST0000-osc-ffff8d51c6102800: disconnect after 21s idle [ 117.881356] Lustre: Mounted lustre-client [ 119.890273] Lustre: DEBUG MARKER: oleg201-client.virtnet: executing check_config_client /mnt/lustre [ 126.720416] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 130.432669] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 04:16:44 (1761121004) [ 132.534403] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 04:16:47 (1761121007) [ 134.901426] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 04:16:49 (1761121009) [ 137.411835] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 04:16:51 (1761121011) [ 139.913190] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 04:16:54 (1761121014) [ 142.179092] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 04:16:56 (1761121016) [ 144.726754] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 04:16:59 (1761121019) [ 147.034160] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 04:17:01 (1761121021) [ 149.135176] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 04:17:03 (1761121023) [ 151.053809] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 04:17:05 (1761121025) [ 154.110306] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 04:17:08 (1761121028) [ 156.161749] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 04:17:10 (1761121030) [ 158.260938] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 04:17:12 (1761121032) [ 160.447077] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 04:17:14 (1761121034) [ 162.594711] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 04:17:17 (1761121037) [ 164.842926] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 04:17:19 (1761121039) [ 166.875805] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 04:17:21 (1761121041) [ 169.204970] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 04:17:23 (1761121043) [ 171.298811] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 04:17:25 (1761121045) [ 173.579906] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 04:17:28 (1761121048) [ 173.735665] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=162129603798761480 file: /mnt/lustre/lockdir/lockfile=144115205289279504 [ 357.046241] hrtimer: interrupt took 3233001 ns [ 364.745735] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 04:20:38 (1761121238) [ 367.931102] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 04:20:42 (1761121242) [ 370.084359] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 04:20:44 (1761121244) [ 372.227186] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 04:20:46 (1761121246) [ 374.343046] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 04:20:48 (1761121248) [ 376.571828] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 04:20:51 (1761121251) [ 377.156333] Lustre: DEBUG MARKER: chmod [ 379.175799] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 04:20:53 (1761121253) [ 381.947622] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7207500kB free gt MAXFREE 800000kB, increase 800000 (or reduce test fs size) to proceed [ 385.614669] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 04:21:00 (1761121260) [ 441.286095] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 04:21:55 (1761121315) [ 467.979745] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 04:22:22 (1761121342) [ 516.111068] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 04:23:10 (1761121390) [ 527.359764] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 04:23:21 (1761121401) [ 529.802123] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 04:23:24 (1761121404) [ 530.465805] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.516893] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.544267] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.572502] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.602935] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.631091] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.655402] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.682206] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.706589] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.729554] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.757749] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.792353] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.819234] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.844291] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.867346] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.889468] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.917928] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.947403] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.975295] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 530.999089] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.024488] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.052618] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.077186] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.105974] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.133222] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.161681] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.188533] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.212055] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.244462] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.269259] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.291778] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.316493] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.347697] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.375207] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.399188] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.429132] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.459797] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.485673] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.505646] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.527647] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.554433] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.583579] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.611293] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.640187] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.669231] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.692922] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.715675] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.743685] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.766112] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.788949] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.810903] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.838406] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.861194] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.888507] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.916951] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.941764] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.964483] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 531.989126] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.012567] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.034917] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.057459] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.081583] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.102380] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.124427] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.150066] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.179612] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.211453] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.234561] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.260798] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.293897] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.323191] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.349576] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.377589] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.402629] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.429494] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.454733] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.481415] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.508424] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.532291] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.561243] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.583950] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.614409] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.637278] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.663362] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.693517] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.720044] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.741866] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.765386] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.791016] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.815423] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.840639] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.860612] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.882136] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.916051] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.944121] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.969067] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 532.994637] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.024234] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.049606] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.074667] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.106070] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.134486] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.161835] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.187133] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.210861] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.239180] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.269941] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.293482] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.320053] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.351193] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.379929] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.411336] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.435441] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.465210] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.490224] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.517897] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.549079] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.582221] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.604786] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.632837] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.659041] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.690314] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.717323] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.744232] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.772680] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.803563] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.829868] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.860358] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.889260] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.913335] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.940589] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.968561] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 533.996815] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.019852] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.042705] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.074587] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.100474] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.126343] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.148498] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.172762] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.200833] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.226584] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.249058] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.279634] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.307993] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.335752] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.362853] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.394902] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.425083] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.455294] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.482054] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.508660] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.537595] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.567770] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.594132] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.624048] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.655465] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.681592] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.705410] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.736331] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.763388] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.786357] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.818412] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.849215] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.881222] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.906056] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.928834] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.957552] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 534.985742] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.018048] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.046365] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.075208] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.099246] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.121611] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.152805] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.186293] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.212178] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.242687] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.268109] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.292850] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.323071] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.352621] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.381293] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.412450] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.445967] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.477810] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.509275] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.540362] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.571986] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.604617] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.634321] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.672112] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.704892] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.733888] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.764685] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.795047] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.833248] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.867128] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.898482] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.927062] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.953263] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 535.984107] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.019291] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.052854] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.083612] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.114744] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.148676] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.183587] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.219435] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.248313] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.281830] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.312572] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.347041] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.385770] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.418527] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.450582] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.484578] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.518346] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.549966] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.581460] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.609204] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.641395] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.673591] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.711273] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.746569] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.782383] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.814084] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.850697] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.883924] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.915582] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.950141] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 536.982617] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.012765] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.042544] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.074730] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.108657] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.139583] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.169960] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.201459] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.232432] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.264777] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.294115] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.324762] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.357187] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.383890] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.419110] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.449858] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.478380] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.508605] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.539874] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.572206] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.601527] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.630326] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.659136] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.693345] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.727957] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.757460] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.786826] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.815599] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.841334] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.865623] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.895136] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.930235] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.963327] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 537.986478] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.011509] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.042099] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.069886] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.099956] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.125165] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.157210] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.189367] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.219940] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.251765] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.278962] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.307769] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.336744] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.364407] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.392950] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.423909] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.451462] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.481727] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.513489] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.547805] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.576531] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.604121] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.634351] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.664173] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.689826] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.717162] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.749313] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.778702] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.813644] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.842949] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.871535] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.904864] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.933569] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.961334] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 538.991272] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.021236] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.046856] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.069425] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.095817] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.124976] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.155574] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.183883] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.208847] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.235531] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.261613] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.290747] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.321293] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.346983] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.375021] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.400533] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.426248] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.451224] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.477578] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.507303] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.539700] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.569080] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.594059] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.621785] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.653684] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.681129] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.709077] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.733592] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.758992] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.785680] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.816461] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.844349] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.870731] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.897510] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.928152] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.958869] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 539.987883] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.016118] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.044447] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.076329] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.107701] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.136602] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.160678] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.187306] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.214254] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.240749] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.264723] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.295189] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.327159] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.353241] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.381066] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.409376] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.441089] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.465731] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.497731] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.527149] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.555203] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.582493] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.614774] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.647129] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.681600] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.707835] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.737052] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.766503] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.803196] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.831748] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.865941] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.894077] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.923737] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.946344] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 540.973112] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.002952] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.033929] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.063659] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.086634] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.114451] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.141336] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.169839] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.197108] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.226869] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.252069] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.280284] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.308892] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.337685] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.367733] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.393717] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.427367] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.451195] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.475700] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.497410] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.518682] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.547159] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.577318] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.601603] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.625259] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.650381] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.678615] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.706182] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.737174] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.768962] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.793338] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.822559] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.849241] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.880530] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.907183] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.936711] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.969938] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 541.999882] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.029214] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.056402] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.081381] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.107107] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.134675] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.164864] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.199279] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.223415] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.250292] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.275282] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.300293] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.327705] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.354101] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.377212] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.404790] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.434001] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.461871] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.491787] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.515208] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.538538] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.564560] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.592415] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.628916] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.663758] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.694154] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.718886] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.740933] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.763458] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.786862] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.818397] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.846467] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.875118] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.901435] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.925692] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.951390] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 542.976040] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.000420] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.027116] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.051222] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.076561] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.104395] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.129201] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.155261] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.181267] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.209500] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.235201] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.260859] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.286555] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.314281] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.344777] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.375066] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.402274] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.427033] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.452588] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.479975] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.505633] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.529246] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.557140] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.585062] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.616734] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.645151] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.674978] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.701875] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.726218] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.751387] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.776068] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.797795] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.823196] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.849199] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.870102] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.893436] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.919454] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.949401] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 543.976146] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.000154] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.024668] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.048640] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.073149] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.104054] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.129305] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.153555] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.180311] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.202732] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.223821] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.246168] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.272414] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.295796] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.316202] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.336641] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.358822] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.380591] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.408673] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.436580] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.463207] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.492174] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.514564] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.541912] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.572575] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.596558] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.622496] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.648664] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.674275] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.700121] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.724758] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.749484] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.773322] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.799081] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.826677] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.856546] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.893397] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.926346] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.956871] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 544.981988] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.011132] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.036674] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.058808] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.088116] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.114276] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.136820] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.159598] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.181144] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.201853] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.225281] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.246776] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.270147] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.294669] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.325769] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.350814] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.381176] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.403769] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.430953] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.457710] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.481436] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.507624] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.535063] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.564437] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.588853] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.614725] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.645968] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.669017] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.690579] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.712051] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.733940] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.759136] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.784783] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.808047] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.838742] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.866519] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.890896] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.918336] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.942136] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.967752] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 545.996525] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.027302] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.053762] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.079674] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.109294] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.131482] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.151710] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.174424] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.206592] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.234592] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.256260] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.277136] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.302658] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.331416] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.356798] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.384359] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.416304] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.442665] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.471211] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.494683] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.518305] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.545789] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.571078] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.592953] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.615107] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.640970] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.669393] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.693208] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.715215] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.737111] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.764730] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.794209] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.820266] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.845236] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.868636] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.891456] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.918348] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.945562] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 546.976866] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.002057] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.031490] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.055569] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.082783] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.112575] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.144912] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.168493] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.189335] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.213651] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.238453] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.262231] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.285801] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.311237] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.343801] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.376128] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.400952] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.427033] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.451471] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.476636] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.501047] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.524852] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.547799] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.573560] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.596084] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.623678] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.651972] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.679517] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.709837] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.737512] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.764324] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.788605] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.814455] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.843388] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.867133] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.889943] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.910735] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.934710] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.956186] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 547.976161] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.005968] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.034960] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.060596] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.081282] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.108822] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.131321] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.156325] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.185361] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.211226] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.235471] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.262733] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.287626] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.313108] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.319194] Lustre: lustre-OST0000-osc-ffff8d51c3de3800: disconnect after 21s idle [ 548.321618] Lustre: Skipped 1 previous similar message [ 548.337024] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.362438] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.382394] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.404458] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.428309] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.453618] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.479733] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.504649] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.530100] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.549425] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.572182] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.591034] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.613118] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.631540] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.649599] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.674734] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.701236] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.721520] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.742333] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.769366] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.796686] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.818839] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.844653] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.866163] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.885916] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.909859] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.937768] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.966881] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 548.990580] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.010981] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.036079] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.062119] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.089053] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.116407] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.141699] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.163412] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.185187] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.214889] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.241847] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.263738] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.289080] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.322879] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.350050] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.376267] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.401782] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.424963] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.445488] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.466588] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.491337] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.516049] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.542923] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.571743] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.598202] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.625912] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.648553] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.673238] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.699813] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.724201] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.752110] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.782471] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.811102] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 549.833927] rw_seq_cst_vs_d (29879): drop_caches: 3 [ 552.275244] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 04:23:46 (1761121426) [ 552.388870] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 552.431612] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 552.451518] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 552.566711] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 552.586707] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 552.610699] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 552.630733] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 552.648214] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 552.664169] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 552.821195] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 552.918464] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.060767] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.078161] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.160107] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.237962] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.254527] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.291963] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.313587] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.330383] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.346996] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.372407] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.479608] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.513508] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.532894] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.587753] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.622173] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.646365] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.664286] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.778261] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 553.890553] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.006195] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.023291] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.113689] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.130248] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.149634] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.174265] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.191775] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.252689] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.383969] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.422573] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.450659] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.472693] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.745441] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.838871] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.901379] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 554.924995] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 555.069572] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 555.124039] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 555.164308] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 555.203024] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 555.294398] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 555.511847] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 555.584286] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 555.604243] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 555.624294] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 555.926036] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.123209] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.160809] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.189177] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.216947] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.237705] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.299079] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.453629] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.652746] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.697158] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.863588] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.917809] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 556.936790] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.031885] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.068368] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.102416] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.118260] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.139750] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.158585] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.231973] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.273123] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.290504] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.313623] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.372256] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.551162] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.569065] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.593038] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.650653] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.749492] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.769137] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.791370] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.833314] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.859527] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.884579] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.906115] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.929250] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.951475] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 557.996833] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.017310] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.058856] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.101312] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.117952] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.141258] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.175319] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.208703] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.246544] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.319446] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.334544] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.367842] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.443919] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.484099] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.565892] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.689804] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.708797] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.746417] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.762622] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.800358] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.855839] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.894962] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.928976] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 558.984305] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.020814] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.061591] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.083586] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.147049] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.222479] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.259487] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.284036] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.305322] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.325280] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.384530] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.482675] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.506388] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.569302] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.585406] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.609203] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.648987] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.672163] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.690941] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.731359] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.753642] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.806319] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.842073] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.902078] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 559.988234] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.017395] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.049316] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.073456] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.116066] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.136738] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.171298] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.210486] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.264427] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.357363] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.393022] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.449813] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.466618] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.544172] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.687034] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.750667] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.775747] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.806455] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.829094] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.852788] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.914294] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.936310] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.959450] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 560.982705] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.002221] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.018466] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.041972] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.063233] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.108495] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.134300] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.162091] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.233163] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.274829] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.386124] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.411225] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.428857] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.453409] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.473727] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.494971] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.512541] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.529187] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.615427] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.632806] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.669762] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.690832] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.713031] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.765858] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.784761] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 561.805915] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.067259] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.091512] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.117449] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.169427] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.189830] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.211303] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.234913] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.259637] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.280111] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.363208] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.380715] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.404177] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.426142] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.446116] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.463140] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.486085] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.506086] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.522670] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.538570] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.671639] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.697595] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.912337] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.927186] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.941922] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.958807] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.978538] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 562.998800] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.018773] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.037143] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.055135] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.154246] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.176737] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.198730] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.420355] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.461733] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.479430] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.501736] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.519320] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.537540] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.555867] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.687058] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.715051] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.795428] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.816710] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.862271] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.880892] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.899339] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.917522] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.942788] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 563.983408] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.001894] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.020715] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.140787] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.164466] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.213051] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.252808] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.301656] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.338874] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.395546] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.412798] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.429086] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.447784] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.518703] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.582911] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.605277] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.622440] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.640555] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.660813] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.682877] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.723327] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.790730] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.887755] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.905911] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.922299] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.939693] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 564.958641] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.013740] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.031031] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.079843] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.096394] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.111838] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.128993] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.147922] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.167347] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.201154] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.291358] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.312498] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.332099] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.372953] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.398254] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.418320] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.671758] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.712224] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.734516] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.765662] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.788970] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.807205] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.850742] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.870446] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.889766] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.930118] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.946097] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.961348] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.983398] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 565.999628] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.016612] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.033592] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.051991] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.096868] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.184073] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.240630] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.256535] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.274690] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.297815] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.316669] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.352541] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.388545] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.409291] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.429499] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.449536] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.462929] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.478947] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.495880] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.517930] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.543093] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.582203] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.602149] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.686667] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.728504] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.764280] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.780023] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.814322] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.833506] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.906761] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.927674] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.945903] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.964104] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 566.979946] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.034275] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.054155] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.068682] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.084192] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.105995] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.200175] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.256490] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.272930] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.389268] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.419382] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.441224] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.559559] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.581508] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.661375] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.712432] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.732642] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.773112] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.792801] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.906704] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.928899] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 567.987950] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.060942] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.142582] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.162628] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.183025] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.225594] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.269773] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.293718] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.434600] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.597573] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.637048] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.661737] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.735170] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.766491] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.783741] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.797894] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.816946] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.832296] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.863091] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.877973] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.895863] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.925614] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.947066] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.960504] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.976958] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 568.991874] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.012487] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.044182] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.059296] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.073360] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.090189] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.108649] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.124754] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.160711] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.176158] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.191496] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.206676] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.225064] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.288454] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.321338] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.404691] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.440446] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.454086] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.469307] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.501260] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.517955] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.574980] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.611387] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.645883] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.666409] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.685363] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.706813] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.723643] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.851412] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.865302] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.878829] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.970592] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 569.985558] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.000129] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.015519] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.045616] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.063373] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.084430] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.107940] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.130123] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.144220] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.159189] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.173820] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.269362] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.289455] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.331674] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.355582] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.375881] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.433431] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.466762] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.483285] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.500939] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.516623] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.532994] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.568194] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.595600] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.613523] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.629967] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.647227] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.666063] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.714140] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.742223] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.764347] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.790405] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.892948] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.918094] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.959315] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 570.976955] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.109367] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.134215] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.160484] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.225388] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.327482] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.522843] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.541421] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.562423] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.644299] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.660675] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.717043] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.735683] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.775311] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.792039] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.842596] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.864022] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.888843] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 571.907640] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 572.221439] rw_seq_cst_vs_d (30462): drop_caches: 3 [ 574.649176] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 04:24:09 (1761121449) [ 576.933937] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 04:24:11 (1761121451) [ 577.033261] bash (31637): drop_caches: 3 [ 580.167234] bash (31637): drop_caches: 3 [ 583.274330] bash (31637): drop_caches: 3 [ 586.496348] bash (31637): drop_caches: 3 [ 589.830867] bash (31637): drop_caches: 3 [ 593.330319] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 04:24:27 (1761121467) [ 597.937655] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 04:24:32 (1761121472) [ 615.253338] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 04:24:49 (1761121489) [ 618.019913] Lustre: DEBUG MARKER: loop 5 [ 619.774942] Lustre: DEBUG MARKER: loop 10 [ 621.522296] Lustre: DEBUG MARKER: loop 15 [ 623.312506] Lustre: DEBUG MARKER: loop 20 [ 626.151975] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 04:25:00 (1761121500) [ 628.264431] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 04:25:02 (1761121502) [ 630.386531] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 04:25:04 (1761121504) [ 650.719199] Lustre: lustre-OST0000-osc-ffff8d51c6102800: disconnect after 20s idle [ 694.184407] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 04:26:08 (1761121568) [ 696.492738] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 04:26:11 (1761121571) [ 698.678589] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 04:26:13 (1761121573) [ 700.993835] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 04:26:15 (1761121575) [ 703.358787] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 04:26:17 (1761121577) [ 706.383638] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 04:26:20 (1761121580) [ 710.368135] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 710.855670] Lustre: DEBUG MARKER: SKIP: sanityn test_28 skipping ALWAYS excluded test 28 [ 711.337174] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 04:26:25 (1761121585) [ 715.411113] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 04:26:29 (1761121589) [ 715.525265] Lustre: *** cfs_fail_loc=314, val=0*** [ 716.575194] Lustre: *** cfs_fail_loc=314, val=0*** [ 716.577368] Lustre: Skipped 2 previous similar messages [ 718.690813] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 04:26:33 (1761121593) [ 728.403105] Lustre: *** cfs_fail_loc=314, val=0*** [ 730.791327] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 04:26:45 (1761121605) [ 730.856850] LustreError: 42653:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 731.870902] LustreError: 11-0: lustre-OST0000-osc-ffff8d51c3de3800: operation ldlm_enqueue to node 192.168.202.101@tcp failed: rc = -107 [ 731.874078] Lustre: lustre-OST0000-osc-ffff8d51c3de3800: Connection to lustre-OST0000 (at 192.168.202.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 731.879230] LustreError: lustre-OST0000-osc-ffff8d51c3de3800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 731.884442] Lustre: lustre-OST0000-osc-ffff8d51c3de3800: Connection restored to (at 192.168.202.101@tcp) [ 733.879090] LustreError: 42653:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 awake [ 735.706195] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 736.253412] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 04:26:50 (1761121610) [ 736.789648] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 737.321914] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-Lock-Cancel ========================================================== 04:26:51 (1761121611) [ 742.880720] LustreError: 166-1: MGC192.168.202.101@tcp: Connection to MGS (at 192.168.202.101@tcp) was lost; in progress operations using this service will fail [ 742.884469] Lustre: lustre-MDT0000-mdc-ffff8d51c6102800: Connection to lustre-MDT0000 (at 192.168.202.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 742.889663] Lustre: Evicted from MGS (at 192.168.202.101@tcp) after server handle changed from 0xe2e7fcd2dd4410fd to 0xe2e7fcd2dd682602 [ 742.892899] Lustre: MGC192.168.202.101@tcp: Connection restored to (at 192.168.202.101@tcp) [ 742.904950] LustreError: 2262:0:(client.c:3161:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000174a80f9 x1846669141747648/t4295003433(4295003433) o101->lustre-MDT0000-mdc-ffff8d51c6102800@192.168.202.101@tcp:12/10 lens 576/600 e 0 to 0 dl 1761121661 ref 2 fl Interpret:RPQU/4/0 rc 301/301 job:'cat.0' [ 746.330768] Lustre: lustre-MDT0000-mdc-ffff8d51c6102800: Connection restored to (at 192.168.202.101@tcp) [ 753.119230] Lustre: lustre-OST0000-osc-ffff8d51c3de3800: disconnect after 22s idle [ 753.122369] Lustre: Skipped 3 previous similar messages [ 757.186763] Lustre: DEBUG MARKER: == sanityn test 33d: DNE distributed operation should trigger COS ========================================================== 04:27:11 (1761121631) [ 792.779637] Lustre: DEBUG MARKER: == sanityn test 33e: DNE local operation shouldn't trigger COS ========================================================== 04:27:47 (1761121667) [ 793.258644] Lustre: DEBUG MARKER: SKIP: sanityn test_33e Need two or more clients, have 1 [ 793.812406] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 04:27:48 (1761121668) [ 839.098338] Lustre: lustre-OST0001-osc-ffff8d51c3de3800: Connection to lustre-OST0001 (at 192.168.202.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 839.103981] Lustre: Skipped 1 previous similar message [ 839.107781] LustreError: lustre-OST0001-osc-ffff8d51c6102800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 839.112833] LustreError: lustre-OST0001-osc-ffff8d51c3de3800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 839.113378] Lustre: lustre-OST0001-osc-ffff8d51c6102800: Connection restored to (at 192.168.202.101@tcp) [ 839.121124] Lustre: Skipped 2 previous similar messages [ 844.218147] Lustre: lustre-OST0000-osc-ffff8d51c6102800: Connection to lustre-OST0000 (at 192.168.202.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 844.222209] Lustre: Skipped 1 previous similar message [ 844.224971] LustreError: lustre-OST0000-osc-ffff8d51c6102800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 844.229926] Lustre: lustre-OST0000-osc-ffff8d51c6102800: Connection restored to (at 192.168.202.101@tcp) [ 857.632770] Lustre: DEBUG MARKER: oleg201-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8d51c3de3800.ost_server_uuid,osc.lustre-OST0000-osc-ffff8d51c6102800.ost_server_uuid 40 [ 858.110888] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d51c3de3800.ost_server_uuid in FULL state after 0 sec [ 858.583579] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d51c6102800.ost_server_uuid in FULL state after 0 sec [ 860.402099] Lustre: DEBUG MARKER: oleg201-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d51c3de3800.ost_server_uuid,osc.lustre-OST0001-osc-ffff8d51c6102800.ost_server_uuid 40 [ 860.640165] Lustre: lustre-OST0001-osc-ffff8d51c6102800: disconnect after 21s idle [ 860.890865] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d51c3de3800.ost_server_uuid in FULL state after 0 sec [ 861.350728] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d51c6102800.ost_server_uuid in IDLE state after 0 sec [ 863.567613] Lustre: DEBUG MARKER: oleg201-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8d51c3de3800.ost_server_uuid,osc.lustre-OST0000-osc-ffff8d51c6102800.ost_server_uuid 40 [ 864.032098] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d51c3de3800.ost_server_uuid in FULL state after 0 sec [ 864.530398] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d51c6102800.ost_server_uuid in FULL state after 0 sec [ 866.482565] Lustre: DEBUG MARKER: oleg201-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d51c3de3800.ost_server_uuid,osc.lustre-OST0001-osc-ffff8d51c6102800.ost_server_uuid 40 [ 866.997049] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d51c3de3800.ost_server_uuid in IDLE state after 0 sec [ 867.489748] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d51c6102800.ost_server_uuid in IDLE state after 0 sec [ 871.226429] Lustre: DEBUG MARKER: oleg201-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8d51c3de3800.ost_server_uuid,osc.lustre-OST0000-osc-ffff8d51c6102800.ost_server_uuid 40 [ 871.718912] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d51c3de3800.ost_server_uuid in FULL state after 0 sec [ 872.253573] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d51c6102800.ost_server_uuid in IDLE state after 0 sec [ 874.183456] Lustre: DEBUG MARKER: oleg201-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d51c3de3800.ost_server_uuid,osc.lustre-OST0001-osc-ffff8d51c6102800.ost_server_uuid 40 [ 874.712667] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d51c3de3800.ost_server_uuid in IDLE state after 0 sec [ 875.194096] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d51c6102800.ost_server_uuid in IDLE state after 0 sec [ 875.763901] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 04:29:10 (1761121750) [ 876.693783] Lustre: DEBUG MARKER: Race attempt 0 [ 878.286536] Lustre: DEBUG MARKER: Wait for 55141 55271 for 60 sec... [ 940.641875] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 04:30:15 (1761121815) [ 946.899588] Lustre: DEBUG MARKER: start test - cycle (0) [ 963.212980] Lustre: DEBUG MARKER: start test - cycle (1) [ 978.057116] Lustre: DEBUG MARKER: start test - cycle (2) [ 993.209510] Lustre: DEBUG MARKER: start test - cycle (3) [ 1008.792686] Lustre: DEBUG MARKER: start test - cycle (4) [ 1024.289227] Lustre: DEBUG MARKER: start test - cycle (5) [ 1039.782355] Lustre: DEBUG MARKER: start test - cycle (6) [ 1056.045636] Lustre: DEBUG MARKER: start test - cycle (7) [ 1070.946961] Lustre: DEBUG MARKER: start test - cycle (8) [ 1086.266056] Lustre: DEBUG MARKER: start test - cycle (9) [ 1101.278510] Lustre: DEBUG MARKER: start test - cycle (10) [ 1118.333024] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 04:33:12 (1761121992) [ 1132.000178] Lustre: lustre-OST0001-osc-ffff8d51c3de3800: disconnect after 25s idle [ 1132.004100] Lustre: Skipped 2 previous similar messages [ 1132.471749] Lustre: DEBUG MARKER: == sanityn test 39a: test from 11063 ============================================================================================ 04:33:26 (1761122006) [ 1134.857346] Lustre: DEBUG MARKER: == sanityn test 39b: 11063 problem 1 ============================================================================================ 04:33:29 (1761122009) [ 1137.958363] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 04:33:32 (1761122012) [ 1141.066993] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 04:33:35 (1761122015) [ 1141.136520] Lustre: *** cfs_fail_loc=411, val=0*** [ 1143.236588] Lustre: DEBUG MARKER: == sanityn test 40a: pdirops: create vs others ======================================================================== 04:33:37 (1761122017) [ 1152.579707] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 04:33:46 (1761122026) [ 1162.727570] Lustre: lustre-OST0000-osc-ffff8d51c6102800: disconnect after 21s idle [ 1167.515850] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 04:34:01 (1761122041) [ 1180.802173] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 04:34:14 (1761122054) [ 1200.107679] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 04:34:33 (1761122073) [ 1218.212318] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 04:34:51 (1761122091) [ 1227.773045] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 04:35:01 (1761122101) [ 1234.399236] Lustre: lustre-OST0001-osc-ffff8d51c3de3800: disconnect after 22s idle [ 1234.402460] Lustre: Skipped 1 previous similar message [ 1235.700259] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 04:35:09 (1761122109) [ 1243.398561] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 04:35:17 (1761122117) [ 1251.262628] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 04:35:25 (1761122125) [ 1259.236198] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 04:35:33 (1761122133) [ 1267.600836] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 04:35:41 (1761122141) [ 1275.689136] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 04:35:49 (1761122149) [ 1284.307819] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 04:35:58 (1761122158) [ 1306.080244] Lustre: lustre-OST0001-osc-ffff8d51c6102800: disconnect after 23s idle [ 1306.083796] Lustre: Skipped 2 previous similar messages [ 2223.576032] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 04:51:37 (1761123097) [ 2232.279081] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 04:51:46 (1761123106) [ 2241.830262] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 04:51:55 (1761123115) [ 2251.733198] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 04:52:05 (1761123125) [ 2261.363757] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 04:52:15 (1761123135) [ 2271.517093] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 04:52:25 (1761123145) [ 2273.763975] Lustre: lustre-OST0000-osc-ffff8d51c6102800: disconnect after 20s idle [ 2281.457839] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 04:52:35 (1761123155) [ 2290.334133] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 04:52:44 (1761123164) [ 2294.242731] Lustre: lustre-OST0001-osc-ffff8d51c6102800: disconnect after 22s idle [ 2299.142493] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 04:52:53 (1761123173) [ 2352.860384] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 04:53:46 (1761123226) [ 2362.256316] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 04:53:56 (1761123236) [ 2371.531910] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 04:54:05 (1761123245) [ 2380.452294] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 04:54:14 (1761123254) [ 2389.643371] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 04:54:23 (1761123263) [ 2398.726757] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 04:54:32 (1761123272) [ 2407.719383] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 04:54:41 (1761123281) [ 2416.801124] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 04:54:50 (1761123290) [ 2426.145166] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 04:55:00 (1761123300) [ 2442.719307] Lustre: lustre-OST0000-osc-ffff8d51c6102800: disconnect after 24s idle [ 2509.958597] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 04:56:24 (1761123384) [ 2632.159251] Lustre: lustre-OST0000-osc-ffff8d51c6102800: disconnect after 20s idle [ 2632.162935] Lustre: Skipped 1 previous similar message [ 2785.760072] Lustre: lustre-OST0000-osc-ffff8d51c6102800: disconnect after 22s idle [ 3344.052642] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 05:10:18 (1761124218) [ 3353.964237] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 05:10:27 (1761124227) [ 3363.991758] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 05:10:37 (1761124237) [ 3374.263622] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 05:10:48 (1761124248) [ 3384.942411] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 05:10:58 (1761124258) [ 3394.981448] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 05:11:08 (1761124268) [ 3405.592704] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 05:11:19 (1761124279) [ 3415.912651] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 05:11:29 (1761124289) [ 3426.566395] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 05:11:40 (1761124300) [ 3436.468225] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 05:11:50 (1761124310) [ 3446.239245] Lustre: lustre-OST0000-osc-ffff8d51c6102800: disconnect after 20s idle [ 3446.242193] Lustre: Skipped 1 previous similar message [ 3542.728501] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 05:13:37 (1761124417) [ 3550.724680] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 05:13:44 (1761124424) [ 3559.855880] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 05:13:53 (1761124433) [ 3569.617241] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 05:14:03 (1761124443) [ 3579.030722] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 05:14:13 (1761124453) [ 3588.568917] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 05:14:22 (1761124462) [ 3598.789780] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 05:14:32 (1761124472) [ 3606.574731] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 05:14:40 (1761124480) [ 3615.794083] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 05:14:49 (1761124489) [ 4086.240795] Lustre: lustre-OST0001-osc-ffff8d51c6102800: disconnect after 20s idle [ 4086.245420] Lustre: Skipped 4 previous similar messages [ 4378.534151] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 05:27:32 (1761125252) [ 4385.201032] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 05:27:39 (1761125259) [ 4392.102281] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 05:27:46 (1761125266) [ 4398.947385] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 05:27:53 (1761125273) [ 4405.375821] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 05:27:59 (1761125279) [ 4412.038546] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 05:28:06 (1761125286) [ 4418.416295] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 05:28:12 (1761125292) [ 4425.227354] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 05:28:19 (1761125299) [ 4431.694739] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 05:28:26 (1761125306) [ 4438.216661] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 05:28:32 (1761125312) [ 4445.222729] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 05:28:39 (1761125319) [ 4452.931923] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 05:28:47 (1761125327) [ 4459.783272] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 05:28:54 (1761125334) [ 4469.512420] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 05:29:02 (1761125342) [ 4486.028413] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 05:29:19 (1761125359) [ 4505.419268] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 05:29:38 (1761125378) [ 4529.244220] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 05:30:02 (1761125402) [ 4529.861341] LustreError: 5562:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4531.983468] LustreError: 5562:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 awake [ 4543.655526] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 05:30:17 (1761125417) [ 4552.829816] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 05:30:26 (1761125426) [ 4553.124943] LustreError: 268640:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4557.208199] LustreError: 268640:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 4557.257957] LustreError: 268640:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4561.319213] LustreError: 268640:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 4561.404486] LustreError: 268646:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4565.471185] LustreError: 268646:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 4572.091658] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 05:30:45 (1761125445) [ 4583.801880] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 05:30:57 (1761125457) [ 4590.389491] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 05:31:04 (1761125464) [ 4597.995765] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 05:31:11 (1761125471) [ 4629.229213] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 05:31:42 (1761125502) [ 4641.666353] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 05:31:55 (1761125515) [ 4653.824539] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 05:32:07 (1761125527) [ 4671.316443] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 05:32:25 (1761125545) [ 4685.435935] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 05:32:39 (1761125559) [ 4691.391044] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4697.473688] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 05:32:51 (1761125571) [ 4703.337547] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 05:32:57 (1761125577) [ 4709.544783] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 05:33:03 (1761125583) [ 4715.820530] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 05:33:09 (1761125589) [ 4741.497177] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 05:33:35 (1761125615) [ 4789.753817] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 05:34:22 (1761125662) [ 4800.549680] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 05:34:33 (1761125673) [ 4807.992146] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 05:34:41 (1761125681) [ 4811.404412] LustreError: 11-0: lustre-MDT0000-mdc-ffff8d51c3de3800: operation ldlm_enqueue to node 192.168.202.101@tcp failed: rc = -35 [ 4820.406904] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 05:34:53 (1761125693) [ 4821.070315] LustreError: 2264:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4823.159371] LustreError: 2264:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d awake [ 4835.704612] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 05:35:08 (1761125708) [ 4844.002306] Lustre: lustre-OST0000-osc-ffff8d51c3de3800: disconnect after 24s idle [ 4844.005043] Lustre: Skipped 6 previous similar messages [ 4981.595800] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 05:37:35 (1761125855) [ 4988.102534] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 05:37:42 (1761125862) [ 4997.558216] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 05:37:51 (1761125871) [ 5010.568399] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 05:38:04 (1761125884) [ 5024.192835] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 05:38:18 (1761125898) [ 5047.536445] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 05:38:41 (1761125921) [ 5074.576522] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 05:39:07 (1761125947) [ 5085.373193] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 05:39:19 (1761125959) [ 5095.559940] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 05:39:29 (1761125969) [ 5115.743870] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 05:39:49 (1761125989) [ 5166.898735] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 05:40:40 (1761126040) [ 5295.402919] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with NID/JobID/OPCode expression ========================================================== 05:42:49 (1761126169) [ 5473.761223] Lustre: lustre-OST0001-osc-ffff8d51c6102800: disconnect after 22s idle [ 5473.783521] Lustre: Skipped 14 previous similar messages [ 5621.052764] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 05:48:14 (1761126494) [ 5629.801711] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 05:48:23 (1761126503) [ 5687.030513] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 05:49:20 (1761126560) [ 5752.886199] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 05:50:26 (1761126626) [ 5765.429256] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 05:50:39 (1761126639) [ 5870.678271] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 05:52:24 (1761126744) [ 5905.811869] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 05:52:59 (1761126779) [ 5914.977571] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 05:53:08 (1761126788) [ 5933.249525] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 05:53:26 (1761126806) [ 5936.080192] LustreError: 308353:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8d51c6102800: inode [0x200000402:0x7c7:0x0] mdc close failed: rc = -116 [ 5936.599676] LustreError: 308356:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8d51c6102800: inode [0x240000402:0x600:0x0] mdc close failed: rc = -116 [ 5936.612849] LustreError: 308356:0:(file.c:246:ll_close_inode_openhandle()) Skipped 3 previous similar messages [ 5943.948291] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 05:53:37 (1761126817) [ 5945.556143] LustreError: 308964:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8d51c6102800: inode [0x200000402:0x7cb:0x0] mdc close failed: rc = -116 [ 5945.567517] LustreError: 308964:0:(file.c:246:ll_close_inode_openhandle()) Skipped 7 previous similar messages [ 5961.624387] LustreError: 309174:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8d51c6102800: inode [0x200000402:0x816:0x0] mdc close failed: rc = -116 [ 6067.600574] LustreError: 11-0: lustre-MDT0000-mdc-ffff8d51c6102800: operation mds_close to node 192.168.202.101@tcp failed: rc = -107 [ 6067.607528] Lustre: lustre-MDT0000-mdc-ffff8d51c6102800: Connection to lustre-MDT0000 (at 192.168.202.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6067.642069] LustreError: lustre-MDT0000-mdc-ffff8d51c6102800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6067.653371] LustreError: 309205:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8d51c6102800: inode [0x200000402:0x81c:0x0] mdc close failed: rc = -5 [ 6067.656141] Lustre: lustre-MDT0000-mdc-ffff8d51c6102800: Connection restored to (at 192.168.202.101@tcp) [ 6075.029377] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 05:55:48 (1761126948) [ 6082.507518] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 05:55:56 (1761126956) [ 6178.387234] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 05:57:31 (1761127051) [ 6179.963893] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6181.738879] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 05:57:35 (1761127055) [ 6188.592659] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 05:57:42 (1761127062) [ 6317.944987] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 05:59:51 (1761127191) [ 6331.722785] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 06:00:05 (1761127205) [ 6515.711351] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 06:03:09 (1761127389) [ 6699.913879] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 06:06:14 (1761127574) [ 6703.770513] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 06:06:17 (1761127577) [ 6715.341578] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 06:06:29 (1761127589) [ 6715.505515] Lustre: DEBUG MARKER: write [ 6715.534664] LustreError: 296946:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6717.552174] Lustre: DEBUG MARKER: kill 323960 [ 6717.556470] LustreError: 323960:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6720.544484] LustreError: 296946:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 awake [ 6723.599333] LustreError: 323960:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 awake [ 6727.081267] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 06:06:41 (1761127601) [ 6727.346052] LustreError: 324560:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6729.434131] LustreError: 324560:0:(fail.c:144:__cfs_fail_timeout_set()) cfs_fail_timeout interrupted [ 6736.968804] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 06:06:51 (1761127611) [ 6737.840575] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6738.841543] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 06:06:53 (1761127613) [ 6742.503715] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 06:06:56 (1761127616) [ 6745.834721] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 06:07:00 (1761127620) [ 6749.210873] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 06:07:03 (1761127623) [ 6752.453249] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 06:07:06 (1761127626) [ 6755.732611] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 06:07:10 (1761127630) [ 6758.862255] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 06:07:13 (1761127633) [ 6763.045508] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 06:07:17 (1761127637) [ 6767.084283] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 06:07:21 (1761127641) [ 6767.902965] Lustre: *** cfs_fail_loc=415, val=0*** [ 6775.940514] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 06:07:30 (1761127650) [ 6797.696097] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 06:07:52 (1761127672) [ 6797.820372] LustreError: 296946:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6802.919159] LustreError: 296946:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 6808.023445] LustreError: 296946:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 6808.027854] LustreError: 296946:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6808.033294] LustreError: 296946:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 6818.127492] LustreError: 296946:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 6818.130365] LustreError: 296946:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 6828.335269] LustreError: 296946:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6828.338868] LustreError: 296946:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 6838.447209] LustreError: 20937:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 6838.450142] LustreError: 20937:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 6863.946970] LustreError: 5570:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6863.950814] LustreError: 5570:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 6874.143135] LustreError: 5570:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 6874.146307] LustreError: 5570:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 6897.353539] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 06:09:31 (1761127771) [ 6900.387579] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 06:09:34 (1761127774) [ 6903.359390] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 06:09:37 (1761127777) [ 6905.985493] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 06:09:40 (1761127780) [ 6910.719236] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 06:09:45 (1761127785) [ 6919.531287] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 06:09:53 (1761127793) [ 6926.543186] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 06:10:00 (1761127800) [ 6927.513417] Lustre: Unmounted lustre-client [ 6928.380724] Lustre: Unmounted lustre-client [ 6928.995518] Lustre: DEBUG MARKER: Iteration 1 [ 6929.222763] LustreError: 336069:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6929.222823] LustreError: 336068:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6929.228516] LustreError: 336069:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 6929.290981] Lustre: Mounted lustre-client [ 6929.919687] Lustre: Unmounted lustre-client [ 6931.072779] Key type lgssc unregistered [ 6931.188854] LNet: 336414:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6932.256061] LNet: Removed LNI 192.168.202.1@tcp [ 6932.522461] Key type .llcrypt unregistered [ 6932.524518] Key type ._llcrypt unregistered [ 6932.852236] alg: No test for adler32 (adler32-zlib) [ 6933.606363] Key type ._llcrypt registered [ 6933.607719] Key type .llcrypt registered [ 6933.754918] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6933.997555] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6934.273942] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 6934.276165] LNet: Accept secure, port 988 [ 6935.895176] Key type lgssc registered [ 6936.520433] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6942.033436] Lustre: DEBUG MARKER: Iteration 2 [ 6942.148336] LustreError: 337209:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6942.148656] LustreError: 337210:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6942.155071] LustreError: 337209:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6943.221148] Lustre: Mounted lustre-client [ 6943.890659] Lustre: Unmounted lustre-client [ 6944.997196] Key type lgssc unregistered [ 6945.115621] LNet: 337558:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6946.143951] LNet: Removed LNI 192.168.202.1@tcp [ 6946.416837] Key type .llcrypt unregistered [ 6946.418179] Key type ._llcrypt unregistered [ 6946.751078] alg: No test for adler32 (adler32-zlib) [ 6947.502412] Key type ._llcrypt registered [ 6947.504014] Key type .llcrypt registered [ 6947.594278] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6947.734287] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6947.829874] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 6947.832211] LNet: Accept secure, port 988 [ 6949.423261] Key type lgssc registered [ 6949.857332] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6954.401882] Lustre: DEBUG MARKER: Iteration 3 [ 6954.541424] LustreError: 338352:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6954.541477] LustreError: 338351:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6954.549383] LustreError: 338352:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6955.625982] Lustre: Mounted lustre-client [ 6956.437899] Lustre: Unmounted lustre-client [ 6956.439798] Lustre: Skipped 1 previous similar message [ 6957.721123] Key type lgssc unregistered [ 6957.840650] LNet: 338698:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6958.880123] LNet: Removed LNI 192.168.202.1@tcp [ 6959.169197] Key type .llcrypt unregistered [ 6959.170863] Key type ._llcrypt unregistered [ 6959.502787] alg: No test for adler32 (adler32-zlib) [ 6960.256517] Key type ._llcrypt registered [ 6960.257667] Key type .llcrypt registered [ 6960.345118] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6960.474038] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6960.564580] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 6960.566901] LNet: Accept secure, port 988 [ 6962.167143] Key type lgssc registered [ 6962.593352] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6966.835805] Lustre: DEBUG MARKER: Iteration 4 [ 6966.968800] LustreError: 339491:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6966.969106] LustreError: 339492:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6966.974190] LustreError: 339491:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 6968.043736] Lustre: Mounted lustre-client [ 6968.045812] Lustre: Skipped 1 previous similar message [ 6968.615139] Lustre: Unmounted lustre-client [ 6969.663516] Key type lgssc unregistered [ 6969.786452] LNet: 339839:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6970.848173] LNet: Removed LNI 192.168.202.1@tcp [ 6971.121147] Key type .llcrypt unregistered [ 6971.122672] Key type ._llcrypt unregistered [ 6971.468342] alg: No test for adler32 (adler32-zlib) [ 6972.220396] Key type ._llcrypt registered [ 6972.222173] Key type .llcrypt registered [ 6972.319031] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6972.462643] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6972.580316] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 6972.582672] LNet: Accept secure, port 988 [ 6974.192354] Key type lgssc registered [ 6974.678743] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6979.378927] Lustre: DEBUG MARKER: Iteration 5 [ 6979.535209] LustreError: 340633:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6979.535488] LustreError: 340632:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6979.544961] LustreError: 340633:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6980.639298] Lustre: Mounted lustre-client [ 6980.641391] Lustre: Skipped 1 previous similar message [ 6981.288475] Lustre: Unmounted lustre-client [ 6982.532166] Key type lgssc unregistered [ 6982.644780] LNet: 340982:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6983.712125] LNet: Removed LNI 192.168.202.1@tcp [ 6983.991042] Key type .llcrypt unregistered [ 6983.992720] Key type ._llcrypt unregistered [ 6984.309682] alg: No test for adler32 (adler32-zlib) [ 6985.063429] Key type ._llcrypt registered [ 6985.064872] Key type .llcrypt registered [ 6985.160326] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6985.302984] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6985.395578] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 6985.397420] LNet: Accept secure, port 988 [ 6987.002421] Key type lgssc registered [ 6987.472189] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6991.893714] Lustre: DEBUG MARKER: Iteration 6 [ 6992.034114] LustreError: 341774:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6992.034645] LustreError: 341775:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6992.041247] LustreError: 341774:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6993.113695] Lustre: Mounted lustre-client [ 6993.115377] Lustre: Skipped 1 previous similar message [ 6993.676123] Lustre: Unmounted lustre-client [ 6994.803291] Key type lgssc unregistered [ 6994.911601] LNet: 342123:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6995.935748] LNet: Removed LNI 192.168.202.1@tcp [ 6996.206873] Key type .llcrypt unregistered [ 6996.208540] Key type ._llcrypt unregistered [ 6996.563037] alg: No test for adler32 (adler32-zlib) [ 6997.315517] Key type ._llcrypt registered [ 6997.317134] Key type .llcrypt registered [ 6997.415754] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6997.543488] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6997.638489] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 6997.641785] LNet: Accept secure, port 988 [ 6999.247126] Key type lgssc registered [ 6999.786768] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7004.237756] Lustre: DEBUG MARKER: Iteration 7 [ 7004.379028] LustreError: 342918:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7004.379153] LustreError: 342917:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7004.388433] LustreError: 342918:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7005.463693] Lustre: Mounted lustre-client [ 7005.465522] Lustre: Skipped 1 previous similar message [ 7006.063798] Lustre: Unmounted lustre-client [ 7007.125325] Key type lgssc unregistered [ 7007.238689] LNet: 343265:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7008.288196] LNet: Removed LNI 192.168.202.1@tcp [ 7008.552428] Key type .llcrypt unregistered [ 7008.553799] Key type ._llcrypt unregistered [ 7008.841961] alg: No test for adler32 (adler32-zlib) [ 7009.594398] Key type ._llcrypt registered [ 7009.596143] Key type .llcrypt registered [ 7009.685902] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7009.817720] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7009.919541] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7009.921947] LNet: Accept secure, port 988 [ 7011.527146] Key type lgssc registered [ 7012.022272] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7016.500278] Lustre: DEBUG MARKER: Iteration 8 [ 7016.624197] LustreError: 344058:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7016.624255] LustreError: 344059:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7016.632849] LustreError: 344058:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7017.693790] Lustre: Mounted lustre-client [ 7018.290846] Lustre: Unmounted lustre-client [ 7018.292341] Lustre: Skipped 1 previous similar message [ 7019.364774] Key type lgssc unregistered [ 7019.476567] LNet: 344407:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7020.511971] LNet: Removed LNI 192.168.202.1@tcp [ 7020.770234] Key type .llcrypt unregistered [ 7020.771988] Key type ._llcrypt unregistered [ 7021.135909] alg: No test for adler32 (adler32-zlib) [ 7021.888349] Key type ._llcrypt registered [ 7021.889910] Key type .llcrypt registered [ 7021.979573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7022.110985] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7022.205828] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7022.209031] LNet: Accept secure, port 988 [ 7023.799226] Key type lgssc registered [ 7024.297444] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7029.000380] Lustre: DEBUG MARKER: Iteration 9 [ 7029.134404] LustreError: 345199:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7029.140812] LustreError: 345205:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7029.145068] LustreError: 345199:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7030.235672] Lustre: Mounted lustre-client [ 7030.966650] Lustre: Unmounted lustre-client [ 7032.147197] Key type lgssc unregistered [ 7032.265988] LNet: 345553:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7033.312543] LNet: Removed LNI 192.168.202.1@tcp [ 7033.680116] Key type .llcrypt unregistered [ 7033.681977] Key type ._llcrypt unregistered [ 7033.998322] alg: No test for adler32 (adler32-zlib) [ 7034.755398] Key type ._llcrypt registered [ 7034.756959] Key type .llcrypt registered [ 7034.891919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7035.101545] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7035.227024] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7035.229689] LNet: Accept secure, port 988 [ 7036.839340] Key type lgssc registered [ 7037.564497] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7052.063265] Lustre: DEBUG MARKER: Iteration 10 [ 7052.696343] LustreError: 346348:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7052.698189] LustreError: 346347:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7052.719710] LustreError: 346348:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4988 [ 7054.095957] Lustre: Mounted lustre-client [ 7054.106808] Lustre: Skipped 1 previous similar message [ 7055.953113] Lustre: Unmounted lustre-client [ 7059.830415] Key type lgssc unregistered [ 7060.145772] LNet: 346693:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7061.221286] LNet: Removed LNI 192.168.202.1@tcp [ 7062.220812] Key type .llcrypt unregistered [ 7062.224444] Key type ._llcrypt unregistered [ 7063.265909] alg: No test for adler32 (adler32-zlib) [ 7064.149063] Key type ._llcrypt registered [ 7064.151698] Key type .llcrypt registered [ 7064.426745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7064.931925] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7065.267322] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7065.274045] LNet: Accept secure, port 988 [ 7066.949018] Key type lgssc registered [ 7068.505603] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7085.122733] Lustre: DEBUG MARKER: Iteration 11 [ 7085.604944] LustreError: 347487:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7085.609706] LustreError: 347488:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7085.618703] LustreError: 347487:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4984 [ 7087.027967] Lustre: Mounted lustre-client [ 7087.033205] Lustre: Skipped 1 previous similar message [ 7088.966306] Lustre: Unmounted lustre-client [ 7093.071641] Key type lgssc unregistered [ 7093.417628] LNet: 347835:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7094.502061] LNet: Removed LNI 192.168.202.1@tcp [ 7095.736828] Key type .llcrypt unregistered [ 7095.738996] Key type ._llcrypt unregistered [ 7097.264679] alg: No test for adler32 (adler32-zlib) [ 7098.412298] Key type ._llcrypt registered [ 7098.423491] Key type .llcrypt registered [ 7098.782095] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7099.268433] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7099.691045] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7099.698280] LNet: Accept secure, port 988 [ 7101.447099] Key type lgssc registered [ 7103.121522] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7115.900722] Lustre: DEBUG MARKER: Iteration 12 [ 7116.161459] LustreError: 348620:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7116.161501] LustreError: 348635:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7116.175298] LustreError: 348620:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7117.482284] Lustre: Mounted lustre-client [ 7118.902379] Lustre: Unmounted lustre-client [ 7121.391155] Key type lgssc unregistered [ 7121.667804] LNet: 348981:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7122.723582] LNet: Removed LNI 192.168.202.1@tcp [ 7123.298611] Key type .llcrypt unregistered [ 7123.300282] Key type ._llcrypt unregistered [ 7124.098645] alg: No test for adler32 (adler32-zlib) [ 7124.912680] Key type ._llcrypt registered [ 7124.926355] Key type .llcrypt registered [ 7125.114400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7125.336439] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7125.546690] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7125.551165] LNet: Accept secure, port 988 [ 7127.191182] Key type lgssc registered [ 7128.140562] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7137.515754] Lustre: DEBUG MARKER: Iteration 13 [ 7137.867173] LustreError: 349777:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7137.867456] LustreError: 349776:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7137.880107] LustreError: 349777:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [ 7139.161113] Lustre: Mounted lustre-client [ 7140.433370] Lustre: Unmounted lustre-client [ 7142.680351] Key type lgssc unregistered [ 7142.857917] LNet: 350126:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7143.903939] LNet: Removed LNI 192.168.202.1@tcp [ 7144.421462] Key type .llcrypt unregistered [ 7144.423765] Key type ._llcrypt unregistered [ 7145.051341] alg: No test for adler32 (adler32-zlib) [ 7145.814806] Key type ._llcrypt registered [ 7145.819669] Key type .llcrypt registered [ 7146.033259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7146.281780] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7146.443985] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7146.446382] LNet: Accept secure, port 988 [ 7148.095119] Key type lgssc registered [ 7148.872662] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7157.888689] Lustre: DEBUG MARKER: Iteration 14 [ 7158.291798] LustreError: 350918:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7158.296802] LustreError: 350921:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7158.305770] LustreError: 350918:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4986 [ 7159.540822] Lustre: Mounted lustre-client [ 7160.805066] Lustre: Unmounted lustre-client [ 7160.810350] Lustre: Skipped 1 previous similar message [ 7162.810977] Key type lgssc unregistered [ 7162.972616] LNet: 351272:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7164.005893] LNet: Removed LNI 192.168.202.1@tcp [ 7164.527831] Key type .llcrypt unregistered [ 7164.535671] Key type ._llcrypt unregistered [ 7165.057205] alg: No test for adler32 (adler32-zlib) [ 7165.832493] Key type ._llcrypt registered [ 7165.834024] Key type .llcrypt registered [ 7166.013092] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7166.274145] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7166.467053] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7166.471600] LNet: Accept secure, port 988 [ 7168.127157] Key type lgssc registered [ 7169.061752] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7178.449690] Lustre: DEBUG MARKER: Iteration 15 [ 7178.674119] LustreError: 352065:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7178.674351] LustreError: 352066:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7178.687444] LustreError: 352065:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7179.887040] Lustre: Mounted lustre-client [ 7181.014681] Lustre: Unmounted lustre-client [ 7183.276544] Key type lgssc unregistered [ 7183.549688] LNet: 352419:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7184.611663] LNet: Removed LNI 192.168.202.1@tcp [ 7185.178787] Key type .llcrypt unregistered [ 7185.181486] Key type ._llcrypt unregistered [ 7185.675611] alg: No test for adler32 (adler32-zlib) [ 7186.431800] Key type ._llcrypt registered [ 7186.433375] Key type .llcrypt registered [ 7186.651850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7186.926465] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7187.124194] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7187.126202] LNet: Accept secure, port 988 [ 7188.775164] Key type lgssc registered [ 7189.799576] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7198.922981] Lustre: DEBUG MARKER: Iteration 16 [ 7199.250966] LustreError: 353211:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7199.254400] LustreError: 353213:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7199.260984] LustreError: 353211:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [ 7200.530323] Lustre: Mounted lustre-client [ 7201.753624] Lustre: Unmounted lustre-client [ 7201.764268] Lustre: Skipped 1 previous similar message [ 7204.088912] Key type lgssc unregistered [ 7204.379886] LNet: 353564:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7205.408465] LNet: Removed LNI 192.168.202.1@tcp [ 7206.005658] Key type .llcrypt unregistered [ 7206.017223] Key type ._llcrypt unregistered [ 7206.689053] alg: No test for adler32 (adler32-zlib) [ 7207.471196] Key type ._llcrypt registered [ 7207.473435] Key type .llcrypt registered [ 7207.641574] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7207.923598] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7208.143824] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7208.149460] LNet: Accept secure, port 988 [ 7209.807439] Key type lgssc registered [ 7210.639496] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7219.694854] Lustre: DEBUG MARKER: Iteration 17 [ 7220.106442] LustreError: 354361:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7220.112706] LustreError: 354362:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7220.120200] LustreError: 354361:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7221.327504] Lustre: Mounted lustre-client [ 7222.700836] Lustre: Unmounted lustre-client [ 7224.883328] Key type lgssc unregistered [ 7225.085301] LNet: 354714:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7226.144256] LNet: Removed LNI 192.168.202.1@tcp [ 7226.659311] Key type .llcrypt unregistered [ 7226.661767] Key type ._llcrypt unregistered [ 7227.196379] alg: No test for adler32 (adler32-zlib) [ 7227.949617] Key type ._llcrypt registered [ 7227.952161] Key type .llcrypt registered [ 7228.110902] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7228.364765] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7228.536588] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7228.540823] LNet: Accept secure, port 988 [ 7230.191151] Key type lgssc registered [ 7231.296897] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7240.614485] Lustre: DEBUG MARKER: Iteration 18 [ 7240.887369] LustreError: 355502:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7240.892860] LustreError: 355504:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7240.901378] LustreError: 355502:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4985 [ 7242.144915] Lustre: Mounted lustre-client [ 7243.305264] Lustre: Unmounted lustre-client [ 7245.313405] Key type lgssc unregistered [ 7245.478529] LNet: 355858:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7246.503835] LNet: Removed LNI 192.168.202.1@tcp [ 7246.959438] Key type .llcrypt unregistered [ 7246.961413] Key type ._llcrypt unregistered [ 7247.491275] alg: No test for adler32 (adler32-zlib) [ 7248.241537] Key type ._llcrypt registered [ 7248.244101] Key type .llcrypt registered [ 7248.417326] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7248.654980] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7248.825252] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7248.828095] LNet: Accept secure, port 988 [ 7250.503712] Key type lgssc registered [ 7251.264661] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7258.941312] Lustre: DEBUG MARKER: Iteration 19 [ 7259.174215] LustreError: 356652:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7259.174680] LustreError: 356650:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7259.185245] LustreError: 356652:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [ 7260.358578] Lustre: Mounted lustre-client [ 7261.526552] Lustre: Unmounted lustre-client [ 7263.214813] Key type lgssc unregistered [ 7263.390646] LNet: 357001:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7264.416765] LNet: Removed LNI 192.168.202.1@tcp [ 7264.855282] Key type .llcrypt unregistered [ 7264.856684] Key type ._llcrypt unregistered [ 7265.356928] alg: No test for adler32 (adler32-zlib) [ 7266.110628] Key type ._llcrypt registered [ 7266.114216] Key type .llcrypt registered [ 7266.277763] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7266.566327] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7266.751191] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7266.754649] LNet: Accept secure, port 988 [ 7268.423145] Key type lgssc registered [ 7269.365991] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7277.916965] Lustre: DEBUG MARKER: Iteration 20 [ 7278.127897] LustreError: 357792:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7278.128361] LustreError: 357793:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7278.136070] LustreError: 357792:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7279.378969] Lustre: Mounted lustre-client [ 7279.385491] Lustre: Skipped 1 previous similar message [ 7280.461693] Lustre: Unmounted lustre-client [ 7282.676609] Key type lgssc unregistered [ 7282.920897] LNet: 358148:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7283.937891] LNet: Removed LNI 192.168.202.1@tcp [ 7284.578662] Key type .llcrypt unregistered [ 7284.585345] Key type ._llcrypt unregistered [ 7285.117082] alg: No test for adler32 (adler32-zlib) [ 7285.866402] Key type ._llcrypt registered [ 7285.868600] Key type .llcrypt registered [ 7286.043968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7286.271433] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7286.429838] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7286.432984] LNet: Accept secure, port 988 [ 7288.079146] Key type lgssc registered [ 7288.966450] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7296.637068] Lustre: DEBUG MARKER: Iteration 21 [ 7296.944173] LustreError: 358938:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7296.958271] LustreError: 358947:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7296.963898] LustreError: 358938:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4977 [ 7298.176606] Lustre: Mounted lustre-client [ 7299.385102] Lustre: Unmounted lustre-client [ 7301.440404] Key type lgssc unregistered [ 7301.664720] LNet: 359294:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7302.689475] LNet: Removed LNI 192.168.202.1@tcp [ 7303.333109] Key type .llcrypt unregistered [ 7303.334759] Key type ._llcrypt unregistered [ 7303.918582] alg: No test for adler32 (adler32-zlib) [ 7304.684450] Key type ._llcrypt registered [ 7304.686698] Key type .llcrypt registered [ 7304.860107] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7305.150949] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7305.376044] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7305.380527] LNet: Accept secure, port 988 [ 7307.056293] Key type lgssc registered [ 7308.134542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7316.775543] Lustre: DEBUG MARKER: Iteration 22 [ 7316.999495] LustreError: 360081:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7317.018077] LustreError: 360096:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7317.022624] LustreError: 360081:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4985 [ 7318.225523] Lustre: Mounted lustre-client [ 7319.358254] Lustre: Unmounted lustre-client [ 7321.570887] Key type lgssc unregistered [ 7321.803991] LNet: 360438:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7322.850269] LNet: Removed LNI 192.168.202.1@tcp [ 7323.491855] Key type .llcrypt unregistered [ 7323.495855] Key type ._llcrypt unregistered [ 7324.199816] alg: No test for adler32 (adler32-zlib) [ 7324.957354] Key type ._llcrypt registered [ 7324.959975] Key type .llcrypt registered [ 7325.212755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7325.527329] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7325.716801] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7325.721212] LNet: Accept secure, port 988 [ 7327.383159] Key type lgssc registered [ 7328.434116] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7336.999981] Lustre: DEBUG MARKER: Iteration 23 [ 7337.211056] LustreError: 361230:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7337.211373] LustreError: 361227:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7337.223670] LustreError: 361230:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [ 7338.432513] Lustre: Mounted lustre-client [ 7339.497421] Lustre: Unmounted lustre-client [ 7341.533449] Key type lgssc unregistered [ 7341.734038] LNet: 361584:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7342.755056] LNet: Removed LNI 192.168.202.1@tcp [ 7343.168432] Key type .llcrypt unregistered [ 7343.169838] Key type ._llcrypt unregistered [ 7343.765794] alg: No test for adler32 (adler32-zlib) [ 7344.520422] Key type ._llcrypt registered [ 7344.524915] Key type .llcrypt registered [ 7344.686220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7344.889717] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7345.026926] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7345.029537] LNet: Accept secure, port 988 [ 7346.679762] Key type lgssc registered [ 7347.478141] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7354.831870] Lustre: DEBUG MARKER: Iteration 24 [ 7354.997058] LustreError: 362377:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7354.997063] LustreError: 362379:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7355.003812] LustreError: 362377:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [ 7356.170839] Lustre: Mounted lustre-client [ 7356.172492] Lustre: Skipped 1 previous similar message [ 7356.982896] Lustre: Unmounted lustre-client [ 7358.613959] Key type lgssc unregistered [ 7358.772788] LNet: 362729:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7359.840710] LNet: Removed LNI 192.168.202.1@tcp [ 7360.281373] Key type .llcrypt unregistered [ 7360.283451] Key type ._llcrypt unregistered [ 7360.740237] alg: No test for adler32 (adler32-zlib) [ 7361.492573] Key type ._llcrypt registered [ 7361.493858] Key type .llcrypt registered [ 7361.654806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7361.873187] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7362.010364] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7362.013485] LNet: Accept secure, port 988 [ 7363.647151] Key type lgssc registered [ 7364.489296] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7372.579605] Lustre: DEBUG MARKER: Iteration 25 [ 7372.800535] LustreError: 363522:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7372.800629] LustreError: 363523:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7372.811921] LustreError: 363522:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7374.013544] Lustre: Mounted lustre-client [ 7375.146827] Lustre: Unmounted lustre-client [ 7377.249317] Key type lgssc unregistered [ 7377.480698] LNet: 363871:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7378.527951] LNet: Removed LNI 192.168.202.1@tcp [ 7379.132766] Key type .llcrypt unregistered [ 7379.134894] Key type ._llcrypt unregistered [ 7379.756840] alg: No test for adler32 (adler32-zlib) [ 7380.528915] Key type ._llcrypt registered [ 7380.530827] Key type .llcrypt registered [ 7380.686389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7380.938883] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7381.140640] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7381.145753] LNet: Accept secure, port 988 [ 7382.793550] Key type lgssc registered [ 7383.666932] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7391.891680] Lustre: DEBUG MARKER: Iteration 26 [ 7392.160865] LustreError: 364662:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7392.160905] LustreError: 364663:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7392.176062] LustreError: 364662:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [ 7393.322632] Lustre: Mounted lustre-client [ 7394.189556] Lustre: Unmounted lustre-client [ 7395.875579] Key type lgssc unregistered [ 7396.049984] LNet: 365013:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7397.091795] LNet: Removed LNI 192.168.202.1@tcp [ 7397.595198] Key type .llcrypt unregistered [ 7397.596606] Key type ._llcrypt unregistered [ 7398.230308] alg: No test for adler32 (adler32-zlib) [ 7398.982846] Key type ._llcrypt registered [ 7398.985563] Key type .llcrypt registered [ 7399.159527] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7399.363849] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7399.531919] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7399.534681] LNet: Accept secure, port 988 [ 7401.159247] Key type lgssc registered [ 7401.997748] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7409.437568] Lustre: DEBUG MARKER: Iteration 27 [ 7409.640612] LustreError: 365806:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7409.640783] LustreError: 365807:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7409.648238] LustreError: 365806:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7410.778720] Lustre: Mounted lustre-client [ 7411.805206] Lustre: Unmounted lustre-client [ 7413.473917] Key type lgssc unregistered [ 7413.651665] LNet: 366156:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7414.688988] LNet: Removed LNI 192.168.202.1@tcp [ 7415.084518] Key type .llcrypt unregistered [ 7415.086154] Key type ._llcrypt unregistered [ 7415.589200] alg: No test for adler32 (adler32-zlib) [ 7416.340822] Key type ._llcrypt registered [ 7416.345486] Key type .llcrypt registered [ 7416.480069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7416.678097] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7416.823981] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7416.828358] LNet: Accept secure, port 988 [ 7418.455219] Key type lgssc registered [ 7419.268172] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7426.469551] Lustre: DEBUG MARKER: Iteration 28 [ 7426.654286] LustreError: 366947:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7426.654327] LustreError: 366950:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7426.660142] LustreError: 366947:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7427.803199] Lustre: Mounted lustre-client [ 7428.993365] Lustre: Unmounted lustre-client [ 7430.867787] Key type lgssc unregistered [ 7431.038064] LNet: 367300:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7432.096465] LNet: Removed LNI 192.168.202.1@tcp [ 7432.593481] Key type .llcrypt unregistered [ 7432.595417] Key type ._llcrypt unregistered [ 7433.006816] alg: No test for adler32 (adler32-zlib) [ 7433.758472] Key type ._llcrypt registered [ 7433.759971] Key type .llcrypt registered [ 7433.906548] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7434.116381] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7434.260094] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7434.262724] LNet: Accept secure, port 988 [ 7435.897182] Key type lgssc registered [ 7436.631270] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7444.084656] Lustre: DEBUG MARKER: Iteration 29 [ 7444.292555] LustreError: 368095:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7444.294388] LustreError: 368096:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7444.301232] LustreError: 368095:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7445.439650] Lustre: Mounted lustre-client [ 7446.241205] Lustre: Unmounted lustre-client [ 7447.931308] Key type lgssc unregistered [ 7448.095722] LNet: 368448:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7449.124061] LNet: Removed LNI 192.168.202.1@tcp [ 7449.573361] Key type .llcrypt unregistered [ 7449.575729] Key type ._llcrypt unregistered [ 7450.089986] alg: No test for adler32 (adler32-zlib) [ 7450.844380] Key type ._llcrypt registered [ 7450.846072] Key type .llcrypt registered [ 7450.980829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7451.178673] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7451.335756] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7451.341885] LNet: Accept secure, port 988 [ 7452.975136] Key type lgssc registered [ 7453.562737] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7460.060288] Lustre: DEBUG MARKER: Iteration 30 [ 7460.281601] LustreError: 369242:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7460.283108] LustreError: 369243:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7460.291465] LustreError: 369242:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7461.440301] Lustre: Mounted lustre-client [ 7461.442465] Lustre: Skipped 1 previous similar message [ 7462.358117] Lustre: Unmounted lustre-client [ 7464.185399] Key type lgssc unregistered [ 7464.335832] LNet: 369594:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7465.376327] LNet: Removed LNI 192.168.202.1@tcp [ 7465.795801] Key type .llcrypt unregistered [ 7465.797727] Key type ._llcrypt unregistered [ 7466.338231] alg: No test for adler32 (adler32-zlib) [ 7467.105448] Key type ._llcrypt registered [ 7467.107345] Key type .llcrypt registered [ 7467.228391] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7467.416516] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7467.560264] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7467.562926] LNet: Accept secure, port 988 [ 7469.199160] Key type lgssc registered [ 7469.987471] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7476.455457] Lustre: DEBUG MARKER: Iteration 31 [ 7476.586055] LustreError: 370387:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7476.591482] LustreError: 370393:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7476.598711] LustreError: 370387:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 7477.708618] Lustre: Mounted lustre-client [ 7477.710106] Lustre: Skipped 1 previous similar message [ 7478.495106] Lustre: Unmounted lustre-client [ 7479.833717] Key type lgssc unregistered [ 7479.965769] LNet: 370738:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7480.993147] LNet: Removed LNI 192.168.202.1@tcp [ 7481.337372] Key type .llcrypt unregistered [ 7481.339079] Key type ._llcrypt unregistered [ 7481.701441] alg: No test for adler32 (adler32-zlib) [ 7482.454393] Key type ._llcrypt registered [ 7482.456809] Key type .llcrypt registered [ 7482.584608] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7482.738504] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7482.862218] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7482.865473] LNet: Accept secure, port 988 [ 7484.488265] Key type lgssc registered [ 7485.125043] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7491.928270] Lustre: DEBUG MARKER: Iteration 32 [ 7492.081937] LustreError: 371531:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7492.082035] LustreError: 371532:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7492.095823] LustreError: 371531:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7493.217472] Lustre: Mounted lustre-client [ 7493.995055] Lustre: Unmounted lustre-client [ 7495.359992] Key type lgssc unregistered [ 7495.496121] LNet: 371880:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7496.544712] LNet: Removed LNI 192.168.202.1@tcp [ 7496.940204] Key type .llcrypt unregistered [ 7496.941819] Key type ._llcrypt unregistered [ 7497.305672] alg: No test for adler32 (adler32-zlib) [ 7498.058632] Key type ._llcrypt registered [ 7498.060082] Key type .llcrypt registered [ 7498.155636] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7498.290241] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7498.388543] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7498.390871] LNet: Accept secure, port 988 [ 7499.999135] Key type lgssc registered [ 7500.644684] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7506.683668] Lustre: DEBUG MARKER: Iteration 33 [ 7506.810395] LustreError: 372673:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7506.812100] LustreError: 372674:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7506.817729] LustreError: 372673:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7507.911910] Lustre: Mounted lustre-client [ 7508.680523] Lustre: Unmounted lustre-client [ 7508.682111] Lustre: Skipped 1 previous similar message [ 7510.337497] Key type lgssc unregistered [ 7510.523281] LNet: 373022:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7511.585138] LNet: Removed LNI 192.168.202.1@tcp [ 7511.984571] Key type .llcrypt unregistered [ 7511.986026] Key type ._llcrypt unregistered [ 7512.397730] alg: No test for adler32 (adler32-zlib) [ 7513.149368] Key type ._llcrypt registered [ 7513.152317] Key type .llcrypt registered [ 7513.343220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7513.530246] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7513.666868] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7513.669793] LNet: Accept secure, port 988 [ 7515.295135] Key type lgssc registered [ 7516.003085] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7522.461508] Lustre: DEBUG MARKER: Iteration 34 [ 7522.677553] LustreError: 373815:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7522.678672] LustreError: 373817:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7522.687159] LustreError: 373815:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7523.813152] Lustre: Mounted lustre-client [ 7523.816119] Lustre: Skipped 1 previous similar message [ 7524.597107] Lustre: Unmounted lustre-client [ 7526.014718] Key type lgssc unregistered [ 7526.162974] LNet: 374168:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7527.200140] LNet: Removed LNI 192.168.202.1@tcp [ 7527.539996] Key type .llcrypt unregistered [ 7527.541896] Key type ._llcrypt unregistered [ 7528.000047] alg: No test for adler32 (adler32-zlib) [ 7528.752395] Key type ._llcrypt registered [ 7528.754917] Key type .llcrypt registered [ 7528.886825] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7529.082600] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7529.231255] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7529.234363] LNet: Accept secure, port 988 [ 7530.863138] Key type lgssc registered [ 7531.422492] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7536.959549] Lustre: DEBUG MARKER: Iteration 35 [ 7537.144035] LustreError: 374960:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7537.144575] LustreError: 374962:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7537.152066] LustreError: 374960:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7538.279512] Lustre: Mounted lustre-client [ 7539.052580] Lustre: Unmounted lustre-client [ 7540.614938] Key type lgssc unregistered [ 7540.811101] LNet: 375313:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7541.857514] LNet: Removed LNI 192.168.202.1@tcp [ 7542.345852] Key type .llcrypt unregistered [ 7542.347442] Key type ._llcrypt unregistered [ 7542.841717] alg: No test for adler32 (adler32-zlib) [ 7543.588501] Key type ._llcrypt registered [ 7543.591245] Key type .llcrypt registered [ 7543.738813] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7544.001787] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7544.157118] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7544.159799] LNet: Accept secure, port 988 [ 7545.831138] Key type lgssc registered [ 7546.548310] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7553.467718] Lustre: DEBUG MARKER: Iteration 36 [ 7553.652197] LustreError: 376106:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7553.656580] LustreError: 376108:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7553.661163] LustreError: 376106:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7554.807824] Lustre: Mounted lustre-client [ 7555.884206] Lustre: Unmounted lustre-client [ 7555.904264] Lustre: Skipped 1 previous similar message [ 7557.613342] Key type lgssc unregistered [ 7557.749472] LNet: 376454:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7558.816566] LNet: Removed LNI 192.168.202.1@tcp [ 7559.182757] Key type .llcrypt unregistered [ 7559.184309] Key type ._llcrypt unregistered [ 7559.556438] alg: No test for adler32 (adler32-zlib) [ 7560.307331] Key type ._llcrypt registered [ 7560.309201] Key type .llcrypt registered [ 7560.426485] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7560.631523] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7560.768591] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7560.773460] LNet: Accept secure, port 988 [ 7562.391128] Key type lgssc registered [ 7563.073480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7569.623937] Lustre: DEBUG MARKER: Iteration 37 [ 7569.815177] LustreError: 377248:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7569.820066] LustreError: 377249:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7569.824511] LustreError: 377248:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [ 7570.938950] Lustre: Mounted lustre-client [ 7571.750599] Lustre: Unmounted lustre-client [ 7573.220553] Key type lgssc unregistered [ 7573.387673] LNet: 377596:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7574.431800] LNet: Removed LNI 192.168.202.1@tcp [ 7574.801697] Key type .llcrypt unregistered [ 7574.803211] Key type ._llcrypt unregistered [ 7575.187422] alg: No test for adler32 (adler32-zlib) [ 7575.951584] Key type ._llcrypt registered [ 7575.953442] Key type .llcrypt registered [ 7576.092890] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7576.265657] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7576.384257] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7576.386861] LNet: Accept secure, port 988 [ 7578.007146] Key type lgssc registered [ 7578.609755] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7584.428299] Lustre: DEBUG MARKER: Iteration 38 [ 7584.598492] LustreError: 378389:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7584.599098] LustreError: 378390:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7584.605793] LustreError: 378389:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7585.717503] Lustre: Mounted lustre-client [ 7586.512496] Lustre: Unmounted lustre-client [ 7587.796428] Key type lgssc unregistered [ 7587.918330] LNet: 378737:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7588.959816] LNet: Removed LNI 192.168.202.1@tcp [ 7589.253585] Key type .llcrypt unregistered [ 7589.255195] Key type ._llcrypt unregistered [ 7589.566470] alg: No test for adler32 (adler32-zlib) [ 7590.318394] Key type ._llcrypt registered [ 7590.319977] Key type .llcrypt registered [ 7590.426055] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7590.556576] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7590.650496] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7590.652473] LNet: Accept secure, port 988 [ 7592.255134] Key type lgssc registered [ 7592.800789] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7597.389917] Lustre: DEBUG MARKER: Iteration 39 [ 7597.518040] LustreError: 379530:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7597.518068] LustreError: 379531:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7597.524888] LustreError: 379530:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7598.610623] Lustre: Mounted lustre-client [ 7598.611724] Lustre: Skipped 1 previous similar message [ 7599.265228] Lustre: Unmounted lustre-client [ 7600.479416] Key type lgssc unregistered [ 7600.598818] LNet: 379880:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7601.633321] LNet: Removed LNI 192.168.202.1@tcp [ 7601.959605] Key type .llcrypt unregistered [ 7601.963273] Key type ._llcrypt unregistered [ 7602.442090] alg: No test for adler32 (adler32-zlib) [ 7603.194373] Key type ._llcrypt registered [ 7603.195882] Key type .llcrypt registered [ 7603.293514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7603.430469] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7603.529488] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7603.532722] LNet: Accept secure, port 988 [ 7605.143156] Key type lgssc registered [ 7605.670503] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7610.787975] Lustre: DEBUG MARKER: Iteration 40 [ 7610.953839] LustreError: 380673:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7610.954319] LustreError: 380674:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7610.965325] LustreError: 380673:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7612.057813] Lustre: Mounted lustre-client [ 7612.724603] Lustre: Unmounted lustre-client [ 7612.726408] Lustre: Skipped 1 previous similar message [ 7613.981791] Key type lgssc unregistered [ 7614.110045] LNet: 381022:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7615.136133] LNet: Removed LNI 192.168.202.1@tcp [ 7615.437288] Key type .llcrypt unregistered [ 7615.438803] Key type ._llcrypt unregistered [ 7615.759876] alg: No test for adler32 (adler32-zlib) [ 7616.511401] Key type ._llcrypt registered [ 7616.513217] Key type .llcrypt registered [ 7616.613942] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7616.761077] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7616.867244] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7616.869694] LNet: Accept secure, port 988 [ 7618.479132] Key type lgssc registered [ 7618.975607] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7624.088707] Lustre: DEBUG MARKER: Iteration 41 [ 7624.246450] LustreError: 381811:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7624.246777] LustreError: 381813:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7624.252612] LustreError: 381811:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7625.372221] Lustre: Mounted lustre-client [ 7625.375761] Lustre: Skipped 1 previous similar message [ 7626.140185] Lustre: Unmounted lustre-client [ 7627.588244] Key type lgssc unregistered [ 7627.717055] LNet: 382166:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7628.767904] LNet: Removed LNI 192.168.202.1@tcp [ 7629.055317] Key type .llcrypt unregistered [ 7629.057134] Key type ._llcrypt unregistered [ 7629.386162] alg: No test for adler32 (adler32-zlib) [ 7630.140387] Key type ._llcrypt registered [ 7630.142578] Key type .llcrypt registered [ 7630.234793] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7630.389702] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7630.489517] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7630.493212] LNet: Accept secure, port 988 [ 7632.095126] Key type lgssc registered [ 7632.567674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7637.060882] Lustre: DEBUG MARKER: Iteration 42 [ 7637.233612] LustreError: 382960:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7637.234969] LustreError: 382959:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7637.241532] LustreError: 382960:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7638.335627] Lustre: Mounted lustre-client [ 7639.118760] Lustre: Unmounted lustre-client [ 7640.437923] Key type lgssc unregistered [ 7640.586860] LNet: 383310:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7641.632584] LNet: Removed LNI 192.168.202.1@tcp [ 7641.949213] Key type .llcrypt unregistered [ 7641.950928] Key type ._llcrypt unregistered [ 7642.269185] alg: No test for adler32 (adler32-zlib) [ 7643.024457] Key type ._llcrypt registered [ 7643.026057] Key type .llcrypt registered [ 7643.136479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7643.276836] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7643.380362] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7643.382578] LNet: Accept secure, port 988 [ 7644.983158] Key type lgssc registered [ 7645.485962] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7650.360892] Lustre: DEBUG MARKER: Iteration 43 [ 7650.543774] LustreError: 384103:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7650.543836] LustreError: 384104:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7650.550871] LustreError: 384103:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7651.652263] Lustre: Mounted lustre-client [ 7652.332329] Lustre: Unmounted lustre-client [ 7653.530988] Key type lgssc unregistered [ 7653.647605] LNet: 384454:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7654.688237] LNet: Removed LNI 192.168.202.1@tcp [ 7655.021258] Key type .llcrypt unregistered [ 7655.023070] Key type ._llcrypt unregistered [ 7655.312764] alg: No test for adler32 (adler32-zlib) [ 7656.064372] Key type ._llcrypt registered [ 7656.066589] Key type .llcrypt registered [ 7656.171804] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7656.332552] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7656.434475] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7656.436866] LNet: Accept secure, port 988 [ 7658.039161] Key type lgssc registered [ 7658.541996] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7663.268194] Lustre: DEBUG MARKER: Iteration 44 [ 7663.416436] LustreError: 385245:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7663.423538] LustreError: 385248:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7663.427119] LustreError: 385245:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 7664.516515] Lustre: Mounted lustre-client [ 7665.133623] Lustre: Unmounted lustre-client [ 7666.341614] Key type lgssc unregistered [ 7666.479591] LNet: 385599:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7667.488155] LNet: Removed LNI 192.168.202.1@tcp [ 7667.787807] Key type .llcrypt unregistered [ 7667.789270] Key type ._llcrypt unregistered [ 7668.201942] alg: No test for adler32 (adler32-zlib) [ 7668.958434] Key type ._llcrypt registered [ 7668.960747] Key type .llcrypt registered [ 7669.091111] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7669.240045] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7669.362147] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7669.364578] LNet: Accept secure, port 988 [ 7670.975306] Key type lgssc registered [ 7671.515476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7676.569673] Lustre: DEBUG MARKER: Iteration 45 [ 7676.713351] LustreError: 386392:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7676.714398] LustreError: 386391:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7676.721946] LustreError: 386392:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7677.812592] Lustre: Mounted lustre-client [ 7677.813702] Lustre: Skipped 1 previous similar message [ 7678.562592] Lustre: Unmounted lustre-client [ 7678.569828] Lustre: Skipped 1 previous similar message [ 7680.006193] Key type lgssc unregistered [ 7680.129850] LNet: 386741:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7681.184394] LNet: Removed LNI 192.168.202.1@tcp [ 7681.501572] Key type .llcrypt unregistered [ 7681.503498] Key type ._llcrypt unregistered [ 7681.854604] alg: No test for adler32 (adler32-zlib) [ 7682.606387] Key type ._llcrypt registered [ 7682.608111] Key type .llcrypt registered [ 7682.708137] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7682.854637] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7682.961119] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7682.963884] LNet: Accept secure, port 988 [ 7684.575150] Key type lgssc registered [ 7685.219495] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7690.589211] Lustre: DEBUG MARKER: Iteration 46 [ 7690.751381] LustreError: 387533:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7690.751727] LustreError: 387534:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7690.759216] LustreError: 387533:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7691.872131] Lustre: Mounted lustre-client [ 7692.561936] Lustre: Unmounted lustre-client [ 7693.791324] Key type lgssc unregistered [ 7693.907483] LNet: 387884:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7694.946678] LNet: Removed LNI 192.168.202.1@tcp [ 7695.262144] Key type .llcrypt unregistered [ 7695.264172] Key type ._llcrypt unregistered [ 7695.716048] alg: No test for adler32 (adler32-zlib) [ 7696.467460] Key type ._llcrypt registered [ 7696.468991] Key type .llcrypt registered [ 7696.579831] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7696.733620] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7696.840508] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7696.842888] LNet: Accept secure, port 988 [ 7698.463351] Key type lgssc registered [ 7698.968761] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7703.786114] Lustre: DEBUG MARKER: Iteration 47 [ 7703.903289] LustreError: 388676:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7703.904072] LustreError: 388677:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7703.912858] LustreError: 388676:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7705.013769] Lustre: Mounted lustre-client [ 7705.015024] Lustre: Skipped 1 previous similar message [ 7705.756273] Lustre: Unmounted lustre-client [ 7707.069242] Key type lgssc unregistered [ 7707.190565] LNet: 389028:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7708.257978] LNet: Removed LNI 192.168.202.1@tcp [ 7708.593501] Key type .llcrypt unregistered [ 7708.596240] Key type ._llcrypt unregistered [ 7708.909638] alg: No test for adler32 (adler32-zlib) [ 7709.661337] Key type ._llcrypt registered [ 7709.662797] Key type .llcrypt registered [ 7709.766651] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7709.907685] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7710.020794] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7710.023421] LNet: Accept secure, port 988 [ 7711.647146] Key type lgssc registered [ 7712.199031] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7717.474408] Lustre: DEBUG MARKER: Iteration 48 [ 7717.679179] LustreError: 389821:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7717.682383] LustreError: 389822:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7717.688114] LustreError: 389821:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7718.818107] Lustre: Mounted lustre-client [ 7719.653297] Lustre: Unmounted lustre-client [ 7721.027643] Key type lgssc unregistered [ 7721.173541] LNet: 390174:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7722.207804] LNet: Removed LNI 192.168.202.1@tcp [ 7722.526950] Key type .llcrypt unregistered [ 7722.528575] Key type ._llcrypt unregistered [ 7722.892635] alg: No test for adler32 (adler32-zlib) [ 7723.645304] Key type ._llcrypt registered [ 7723.646621] Key type .llcrypt registered [ 7723.746218] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7723.914861] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7724.018331] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7724.020941] LNet: Accept secure, port 988 [ 7725.631080] Key type lgssc registered [ 7726.178164] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7731.020229] Lustre: DEBUG MARKER: Iteration 49 [ 7731.183355] LustreError: 390966:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7731.183475] LustreError: 390967:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7731.192686] LustreError: 390966:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7732.289704] Lustre: Mounted lustre-client [ 7733.010172] Lustre: Unmounted lustre-client [ 7733.020199] Lustre: Skipped 1 previous similar message [ 7734.230131] Key type lgssc unregistered [ 7734.377571] LNet: 391314:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7735.392978] LNet: Removed LNI 192.168.202.1@tcp [ 7735.708082] Key type .llcrypt unregistered [ 7735.709643] Key type ._llcrypt unregistered [ 7736.059524] alg: No test for adler32 (adler32-zlib) [ 7736.813426] Key type ._llcrypt registered [ 7736.815088] Key type .llcrypt registered [ 7736.908244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7737.043083] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7737.150472] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7737.153256] LNet: Accept secure, port 988 [ 7738.760676] Key type lgssc registered [ 7739.273412] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7743.843783] Lustre: DEBUG MARKER: Iteration 50 [ 7743.958951] LustreError: 392105:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7743.966960] LustreError: 392112:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7743.971458] LustreError: 392105:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 7745.055676] Lustre: Mounted lustre-client [ 7745.057033] Lustre: Skipped 1 previous similar message [ 7745.728948] Lustre: Unmounted lustre-client [ 7746.925806] Key type lgssc unregistered [ 7747.056071] LNet: 392456:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7748.064156] LNet: Removed LNI 192.168.202.1@tcp [ 7748.410841] Key type .llcrypt unregistered [ 7748.412481] Key type ._llcrypt unregistered [ 7748.842129] alg: No test for adler32 (adler32-zlib) [ 7749.606362] Key type ._llcrypt registered [ 7749.607809] Key type .llcrypt registered [ 7749.718973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7749.876442] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7749.982812] LNet: Added LNI 192.168.202.1@tcp [8/256/0/180] [ 7749.985227] LNet: Accept secure, port 988 [ 7751.591137] Key type lgssc registered [ 7752.072705] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7757.256473] Lustre: Mounted lustre-client [ 7760.604567] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 06:23:54 (1761128634) [ 7768.543148] Lustre: 393763:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761128636/real 1761128636] req@00000000d8b38d2c x1846677208044544/t0(0) o36->lustre-MDT0001-mdc-ffff8d51f174d000@192.168.202.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1761128643 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'ln.0' [ 7768.553550] Lustre: lustre-MDT0001-mdc-ffff8d51f174d000: Connection to lustre-MDT0001 (at 192.168.202.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7768.570555] Lustre: lustre-MDT0001-mdc-ffff8d51f174d000: Connection restored to (at 192.168.202.101@tcp) [ 7769.055169] Lustre: 393773:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761128637/real 1761128637] req@0000000044a3cdd5 x1846677208044608/t0(0) o101->lustre-MDT0000-mdc-ffff8d51f174d000@192.168.202.101@tcp:12/10 lens 576/1376 e 0 to 1 dl 1761128644 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'stat.0' [ 7769.065611] Lustre: lustre-MDT0000-mdc-ffff8d51f174d000: Connection to lustre-MDT0000 (at 192.168.202.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7769.080212] Lustre: lustre-MDT0000-mdc-ffff8d51f174d000: Connection restored to (at 192.168.202.101@tcp) [ 7775.711194] Lustre: 393763:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761128643/real 1761128643] req@00000000d8b38d2c x1846677208044544/t0(0) o36->lustre-MDT0001-mdc-ffff8d51f174d000@192.168.202.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1761128650 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 7775.722293] Lustre: lustre-MDT0001-mdc-ffff8d51f174d000: Connection to lustre-MDT0001 (at 192.168.202.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7775.736406] Lustre: lustre-MDT0001-mdc-ffff8d51f174d000: Connection restored to (at 192.168.202.101@tcp) [ 7782.880273] Lustre: 393763:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761128650/real 1761128650] req@00000000d8b38d2c x1846677208044544/t0(0) o36->lustre-MDT0001-mdc-ffff8d51f174d000@192.168.202.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1761128657 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 7782.891337] Lustre: lustre-MDT0001-mdc-ffff8d51f174d000: Connection to lustre-MDT0001 (at 192.168.202.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7782.912414] Lustre: lustre-MDT0001-mdc-ffff8d51f174d000: Connection restored to (at 192.168.202.101@tcp) [ 7789.024144] Lustre: 393763:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761128657/real 1761128657] req@00000000d8b38d2c x1846677208044544/t0(0) o36->lustre-MDT0001-mdc-ffff8d51f174d000@192.168.202.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1761128664 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 7789.034473] Lustre: lustre-MDT0001-mdc-ffff8d51f174d000: Connection to lustre-MDT0001 (at 192.168.202.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7789.050627] Lustre: lustre-MDT0001-mdc-ffff8d51f174d000: Connection restored to (at 192.168.202.101@tcp) [ 7799.427718] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 06:24:33 (1761128673) [ 7806.519337] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 06:24:40 (1761128680) [ 7812.699290] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 06:24:47 (1761128687) [ 7815.779510] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 7816.564871] Lustre: DEBUG MARKER: == sanityn test complete, duration 7701 sec ============== 06:24:50 (1761128690) [ 7937.989148] Lustre: Unmounted lustre-client [ 7974.361290] Key type lgssc unregistered [ 7974.488949] LNet: 397034:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7975.519990] LNet: Removed LNI 192.168.202.1@tcp [ 7975.798328] Key type .llcrypt unregistered [ 7975.800109] Key type ._llcrypt unregistered