[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 426270139 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 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.001010] APIC: Switch to symmetric I/O mode setup [ 0.002232] x2apic enabled [ 0.003004] Switched APIC routing to physical x2apic. [ 0.003986] kvm-guest: setup PV IPIs [ 0.006239] ..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.007027] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008012] pid_max: default: 32768 minimum: 301 [ 0.010016] LSM: Security Framework initializing [ 0.011054] Yama: becoming mindful. [ 0.012031] SELinux: Initializing. [ 0.013074] *** VALIDATE selinux *** [ 0.021712] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026607] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028062] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029128] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031126] *** VALIDATE tmpfs *** [ 0.032462] *** VALIDATE proc *** [ 0.033274] *** VALIDATE cgroup *** [ 0.034015] *** VALIDATE cgroup2 *** [ 0.035345] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037140] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038014] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039037] Spectre V2 : User space: Vulnerable [ 0.040011] Speculative Store Bypass: Vulnerable [ 0.042862] debug: unmapping init [mem 0xffffffffbd259000-0xffffffffbd260fff] [ 0.044326] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045787] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046025] ... version: 2 [ 0.047015] ... bit width: 48 [ 0.048011] ... generic registers: 4 [ 0.049015] ... value mask: 0000ffffffffffff [ 0.050014] ... max period: 00007fffffffffff [ 0.051011] ... fixed-purpose events: 3 [ 0.052010] ... event mask: 000000070000000f [ 0.053401] rcu: Hierarchical SRCU implementation. [ 0.055994] smp: Bringing up secondary CPUs ... [ 0.057745] x86: Booting SMP configuration: [ 0.058116] .... node #0, CPUs: #1 #2 #3 [ 0.063023] smp: Brought up 1 node, 4 CPUs [ 0.065020] smpboot: Max logical packages: 1 [ 0.066031] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.138048] node 0 deferred pages initialised in 68ms [ 0.143010] devtmpfs: initialized [ 0.144318] x86/mm: Memory block size: 128MB [ 0.147067] gcov: version magic: 0x41383552 [ 0.150111] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.151091] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.152263] pinctrl core: initialized pinctrl subsystem [ 0.153227] [ 0.153797] ************************************************************* [ 0.154013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.155012] ** ** [ 0.156014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.157014] ** ** [ 0.158019] ** This means that this kernel is built to expose internal ** [ 0.159017] ** IOMMU data structures, which may compromise security on ** [ 0.160013] ** your system. ** [ 0.161013] ** ** [ 0.162014] ** If you see this message and you are not debugging the ** [ 0.163013] ** kernel, report this immediately to your vendor! ** [ 0.164015] ** ** [ 0.165011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.166011] ************************************************************* [ 0.167504] NET: Registered protocol family 16 [ 0.169477] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.172080] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.175069] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.179098] cpuidle: using governor menu [ 0.180965] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.182630] PCI: Using configuration type 1 for base access [ 0.185116] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.194084] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.196107] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.200086] cryptd: max_cpu_qlen set to 1000 [ 0.203325] ACPI: Added _OSI(Module Device) [ 0.205016] ACPI: Added _OSI(Processor Device) [ 0.207014] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.208014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.213439] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.219424] ACPI: Interpreter enabled [ 0.220059] ACPI: PM: (supports S0 S3 S4 S5) [ 0.222015] ACPI: Using IOAPIC for interrupt routing [ 0.224099] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.227513] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.239984] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.243049] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.246240] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.250208] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.255823] acpiphp: Slot [2] registered [ 0.258209] acpiphp: Slot [5] registered [ 0.259112] acpiphp: Slot [6] registered [ 0.260319] acpiphp: Slot [3] registered [ 0.262136] acpiphp: Slot [4] registered [ 0.263150] acpiphp: Slot [7] registered [ 0.264206] acpiphp: Slot [8] registered [ 0.266159] acpiphp: Slot [9] registered [ 0.268178] acpiphp: Slot [10] registered [ 0.270177] acpiphp: Slot [11] registered [ 0.271198] acpiphp: Slot [12] registered [ 0.273150] acpiphp: Slot [13] registered [ 0.275228] acpiphp: Slot [14] registered [ 0.276115] acpiphp: Slot [15] registered [ 0.278089] acpiphp: Slot [16] registered [ 0.279126] acpiphp: Slot [17] registered [ 0.281097] acpiphp: Slot [18] registered [ 0.282091] acpiphp: Slot [19] registered [ 0.284082] acpiphp: Slot [20] registered [ 0.286121] acpiphp: Slot [21] registered [ 0.287090] acpiphp: Slot [22] registered [ 0.289133] acpiphp: Slot [23] registered [ 0.290100] acpiphp: Slot [24] registered [ 0.292098] acpiphp: Slot [25] registered [ 0.293101] acpiphp: Slot [26] registered [ 0.295112] acpiphp: Slot [27] registered [ 0.296111] acpiphp: Slot [28] registered [ 0.298154] acpiphp: Slot [29] registered [ 0.299103] acpiphp: Slot [30] registered [ 0.301168] acpiphp: Slot [31] registered [ 0.302086] PCI host bridge to bus 0000:00 [ 0.304083] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.306024] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.308081] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.310029] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.313047] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.316029] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.317225] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.320469] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.324528] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.333026] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.338904] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.341047] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.344033] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.347034] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.349563] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.351807] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.354045] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.357913] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.363018] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.372016] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.377016] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.382711] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.405025] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.427023] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.444015] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.453319] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.462018] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.468017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.485014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.499694] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.501340] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.503359] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.504261] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.506226] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.510115] iommu: Default domain type: Passthrough [ 0.512700] SCSI subsystem initialized [ 0.513134] ACPI: bus type USB registered [ 0.514074] usbcore: registered new interface driver usbfs [ 0.516080] usbcore: registered new interface driver hub [ 0.518075] usbcore: registered new device driver usb [ 0.520236] pps_core: LinuxPPS API ver. 1 registered [ 0.522011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.523063] PTP clock support registered [ 0.526047] EDAC MC: Ver: 3.0.0 [ 0.528105] PCI: Using ACPI for IRQ routing [ 0.529492] NetLabel: Initializing [ 0.530007] NetLabel: domain hash size = 128 [ 0.531008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.532060] NetLabel: unlabeled traffic allowed by default [ 0.534037] vgaarb: loaded [ 0.535274] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.537016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.546866] clocksource: Switched to clocksource kvm-clock [ 0.670280] VFS: Disk quotas dquot_6.6.0 [ 0.671180] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.672724] *** VALIDATE ramfs *** [ 0.673503] *** VALIDATE hugetlbfs *** [ 0.674377] pnp: PnP ACPI init [ 0.675808] pnp: PnP ACPI: found 6 devices [ 0.693650] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.695705] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.697561] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.699703] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.701939] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.703545] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.705286] NET: Registered protocol family 2 [ 0.707265] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.711361] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.713466] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.717681] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.719873] TCP: Hash tables configured (established 65536 bind 65536) [ 0.722427] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.724720] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.726378] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.728367] NET: Registered protocol family 1 [ 0.731061] RPC: Registered named UNIX socket transport module. [ 0.732324] RPC: Registered udp transport module. [ 0.733370] RPC: Registered tcp transport module. [ 0.734407] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.735846] NET: Registered protocol family 44 [ 0.737117] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.739232] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.741460] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.743532] PCI: CLS 0 bytes, default 64 [ 0.745176] Unpacking initramfs... [ 2.234284] debug: unmapping init [mem 0xffff9d853cc64000-0xffff9d853ffcffff] [ 2.238529] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.240920] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.244098] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.872059] Initialise system trusted keyrings [ 2.873412] Key type blacklist registered [ 2.875487] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.886472] zbud: loaded [ 2.889676] *** VALIDATE nfs *** [ 2.891489] *** VALIDATE nfs4 *** [ 2.893553] pstore: using deflate compression [ 2.898478] Platform Keyring initialized [ 3.030665] NET: Registered protocol family 38 [ 3.032375] Key type asymmetric registered [ 3.033476] Asymmetric key parser 'x509' registered [ 3.035176] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.038735] io scheduler mq-deadline registered [ 3.040448] io scheduler kyber registered [ 3.042477] io scheduler bfq registered [ 3.043989] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.046266] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.048468] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.050127] ACPI: Power Button [PWRF] [ 3.055981] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.061413] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.067974] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.094772] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.120423] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.124464] Non-volatile memory driver v1.3 [ 3.125480] Linux agpgart interface v0.103 [ 3.150917] virtio_blk virtio1: [vda] 146144 512-byte logical blocks (74.8 MB/71.4 MiB) [ 3.153738] vda: detected capacity change from 0 to 74825728 [ 3.168303] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.170289] vdb: detected capacity change from 0 to 1073741824 [ 3.175416] libphy: Fixed MDIO Bus: probed [ 3.199634] usbcore: registered new interface driver usbserial_generic [ 3.203169] usbserial: USB Serial support registered for generic [ 3.206788] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.212439] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.214597] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.217113] mousedev: PS/2 mouse device common for all mice [ 3.221297] rtc_cmos 00:05: RTC can wake from S4 [ 3.226162] rtc_cmos 00:05: registered as rtc0 [ 3.228427] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.232336] intel_pstate: CPU model not supported [ 3.235095] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.239834] hid: raw HID events driver (C) Jiri Kosina [ 3.245272] usbcore: registered new interface driver usbhid [ 3.247573] usbhid: USB HID core driver [ 3.249459] drop_monitor: Initializing network drop monitor service [ 3.253301] Initializing XFRM netlink socket [ 3.256317] NET: Registered protocol family 10 [ 3.260613] Segment Routing with IPv6 [ 3.261853] NET: Registered protocol family 17 [ 3.262848] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.265933] mpls_gso: MPLS GSO support [ 3.272095] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.279221] RAS: Correctable Errors collector initialized. [ 3.281774] AVX version of gcm_enc/dec engaged. [ 3.282958] AES CTR mode by8 optimization enabled [ 3.348091] sched_clock: Marking stable (3348051482, 0)->(4174028972, -825977490) [ 3.350565] registered taskstats version 1 [ 3.352141] Loading compiled-in X.509 certificates [ 3.353632] zswap: loaded using pool lzo/zbud [ 3.373571] Key type big_key registered [ 3.383549] Key type encrypted registered [ 3.384433] ima: No TPM chip found, activating TPM-bypass! [ 3.385683] ima: Allocated hash algorithm: sha1 [ 3.386664] ima: No architecture policies found [ 3.387578] evm: Initialising EVM extended attributes: [ 3.388545] evm: security.selinux [ 3.389105] evm: security.ima [ 3.390035] evm: security.capability [ 3.391181] evm: HMAC attrs: 0x1 [ 3.393167] rtc_cmos 00:05: setting system clock to 2026-08-25 21:08:16 UTC (1787692096) [ 3.397243] debug: unmapping init [mem 0xffffffffbe203000-0xffffffffbe3fffff] [ 3.399879] debug: unmapping init [mem 0xffffffffbcf82000-0xffffffffbd258fff] [ 3.408053] Write protecting the kernel read-only data: 28672k [ 3.410054] debug: unmapping init [mem 0xffffffffbb603000-0xffffffffbb7fffff] [ 3.411487] debug: unmapping init [mem 0xffffffffbbf14000-0xffffffffbbffffff] [ 3.436883] 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.442308] systemd[1]: Detected virtualization kvm. [ 3.443954] systemd[1]: Detected architecture x86-64. [ 3.445259] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.468715] systemd[1]: No hostname configured. [ 3.469866] systemd[1]: Set hostname to . [ 3.471120] random: systemd: uninitialized urandom read (16 bytes read) [ 3.472441] systemd[1]: Initializing machine ID from random generator. [ 3.621161] random: systemd: uninitialized urandom read (16 bytes read) [ 3.622868] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.626366] random: systemd: uninitialized urandom read (16 bytes read) [ 3.630301] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.641286] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Swap. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Journal Service. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.323459] device-mapper: uevent: version 1.0.3 [ 4.324622] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.122397] virtio_net virtio0 ens2: renamed from eth0 [ 5.147801] scsi host0: ata_piix [ 5.168222] scsi host1: ata_piix [ 5.171737] random: fast init done [ 5.171913] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.178927] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.689103] dracut-initqueue[585]: RTNETLINK answers: File exists [ 9.970473] random: crng init done [ 9.975590] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 11.890874] 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 Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ 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 Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 14.286909] printk: systemd: 23 output lines suppressed due to ratelimiting [ 14.620911] SELinux: Disabled at runtime. [ 14.694808] 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) [ 14.701492] systemd[1]: Detected virtualization kvm. [ 14.702854] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 15.403917] systemd[1]: initrd-switch-root.service: Succeeded. [ 15.407465] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 15.418616] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 15.425937] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 15.430733] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 15.438325] systemd[1]: Starting Journal Service... Starting Journal Service... [ 15.445272] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-serial\x2dgetty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Switch Root. [ 15.496685] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on initctl Compatibility Named Pipe. Starting Remount Root and Kernel File Systems... Starting Create list of required st…ce nodes for the current kernel... Mounting Huge Pages File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Stopped target Initrd Root File System. Starting Apply Kernel Variables... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Mounting Kernel Debug File System... [ OK ] Reached target Slices. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Mounting POSIX Message Queue File System... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. 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 ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 16.336294] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 16.857354] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 16.886968] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 18.144372] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 18.342434] EDAC sbridge: Ver: 1.1.2 [ 19.435130] Key type dns_resolver registered [ 19.821087] NFS: Registering the id_resolver key type [ 19.823166] Key type id_resolver registered [ 19.824747] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting 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 ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... 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 ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ 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 Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg137-client login: [ 40.723419] hrtimer: interrupt took 5409123 ns [ 88.397673] libcfs: loading out-of-tree module taints kernel. [ 88.637154] Key type ._llcrypt registered [ 88.641646] Key type .llcrypt registered [ 89.790713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 89.816473] alg: No test for adler32 (adler32-zlib) [ 91.436540] Lustre: Lustre: Build Version: 2.17.57_80_g4b309f5 [ 92.503168] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [ 94.312176] Key type lgssc registered [ 95.856335] Lustre: Echo OBD driver; http://www.lustre.org/ [ 222.418790] Lustre: Mounted lustre-client - version 2.17.57_80_g4b309f5 [ 227.113681] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 240.062978] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing check_logdir /tmp/testlogs/ [ 245.826878] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing yml_node [ 248.292217] Lustre: lustre-OST0000-osc-ffff9d8590d43000: disconnect after 24s idle [ 249.642329] Lustre: DEBUG MARKER: Client: 2.17.57.80 [ 252.239249] Lustre: DEBUG MARKER: MDS: 2.17.57.80 [ 254.904659] Lustre: DEBUG MARKER: OSS: 2.17.57.80 [ 256.865857] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Tue Aug 25 17:12:28 EDT 2026 [ 274.607230] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 276.219592] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 277.749311] Lustre: DEBUG MARKER: === sanityn: start setup 17:12:49 (1787692369) === [ 278.358185] Lustre: Mounted lustre-client - version 2.17.57_80_g4b309f5 [ 283.511751] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing check_config_client /mnt/lustre [ 302.852881] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 315.128769] Lustre: DEBUG MARKER: === sanityn: finish setup 17:13:26 (1787692406) === [ 317.112943] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 17:13:28 (1787692408) [ 324.610584] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 17:13:36 (1787692416) [ 331.267846] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 17:13:42 (1787692422) [ 338.540758] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 17:13:50 (1787692430) [ 345.159850] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 17:13:56 (1787692436) [ 351.097870] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 17:14:03 (1787692443) [ 357.715620] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 17:14:09 (1787692449) [ 364.321483] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 17:14:16 (1787692456) [ 365.868452] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 367.885586] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 17:14:19 (1787692459) [ 374.678805] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 17:14:26 (1787692466) [ 381.502825] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 17:14:33 (1787692473) [ 389.464769] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 17:14:40 (1787692480) [ 391.137950] Lustre: lustre-OST0000-osc-ffff9d8590d43000: disconnect after 23s idle [ 391.144030] Lustre: Skipped 1 previous similar message [ 397.091539] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 17:14:48 (1787692488) [ 403.166800] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 17:14:54 (1787692494) [ 406.498710] Lustre: lustre-OST0001-osc-ffff9d858c4eb000: disconnect after 24s idle [ 410.517915] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 17:15:02 (1787692502) [ 417.193700] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 17:15:08 (1787692508) [ 421.857404] Lustre: lustre-OST0000-osc-ffff9d8590d43000: disconnect after 22s idle [ 424.880434] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 17:15:16 (1787692516) [ 431.555601] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 17:15:23 (1787692523) [ 439.194258] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 17:15:30 (1787692530) [ 446.003831] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 17:15:37 (1787692537) [ 453.326551] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 17:15:44 (1787692544) [ 453.820163] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 597.525510] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 17:18:08 (1787692688) [ 606.782844] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 17:18:17 (1787692697) [ 615.569886] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 17:18:26 (1787692706) [ 622.031468] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 17:18:33 (1787692713) [ 627.920448] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 17:18:39 (1787692719) [ 635.112289] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 17:18:47 (1787692727) [ 637.006294] Lustre: DEBUG MARKER: chmod [ 644.378654] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 17:18:55 (1787692735) [ 679.148133] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7531520KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 696.231235] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 17:19:47 (1787692787) [ 745.146746] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 17:20:36 (1787692836) [ 774.551598] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 17:21:06 (1787692866) [ 776.685579] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 778.230111] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 17:21:10 (1787692870) [ 821.218691] Lustre: lustre-OST0001-osc-ffff9d858c4eb000: disconnect after 23s idle [ 824.291744] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 17:21:56 (1787692916) [ 831.676428] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 17:22:03 (1787692923) [ 833.128958] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 833.257903] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 833.326982] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 833.417608] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 833.498918] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 833.568529] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 833.627789] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 833.725550] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 833.815820] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 833.882535] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 833.952816] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.029643] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.094341] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.169846] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.244385] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.300427] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.366537] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.435539] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.508854] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.578212] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.651412] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.703644] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.767583] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.826632] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.891701] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 834.979583] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.051299] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.129114] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.207437] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.286131] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.370269] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.508109] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.588125] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.647576] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.732829] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.826742] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.897720] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 835.965639] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.008820] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.076315] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.116071] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.172525] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.242389] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.298773] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.357499] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.430532] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.492273] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.560583] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.639417] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.718914] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.789617] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.848125] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.921707] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 836.995655] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.070750] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.148393] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.226812] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.295376] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.350095] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.401153] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.450934] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.506588] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.595509] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.655658] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.723562] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.785531] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.817313] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.850744] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.885325] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.926832] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.961574] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 837.998134] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.078694] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.136583] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.171534] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.213871] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.256784] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.305406] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.369127] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.425430] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.493615] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.563087] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.632337] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.727638] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.799848] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.869762] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 838.940925] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.023129] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.080896] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.147714] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.220284] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.289635] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.394139] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.457895] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.527316] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.582118] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.639687] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.707594] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.766289] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.825739] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.885819] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 839.955157] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.029541] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.105635] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.169485] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.212904] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.251670] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.307676] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.387776] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.443371] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.535832] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.635157] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.735195] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.819461] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 840.939269] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.060787] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.141065] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.218090] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.295167] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.386487] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.458312] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.550634] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.622399] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.682681] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.764672] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.815339] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.895325] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 841.962208] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.008562] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.058673] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.112209] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.192795] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.259197] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.342380] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.399316] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.455908] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.527644] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.633756] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.725622] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.778044] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.854679] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.949231] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 842.999713] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.063814] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.118659] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.172713] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.231156] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.297900] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.344801] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.399099] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.465306] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.536148] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.607195] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.684778] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.803655] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.848917] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.893708] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.939675] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 843.978073] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.020459] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.061132] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.122405] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.176467] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.256243] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.410272] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.489682] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.592443] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.696289] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.772907] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.848319] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.913708] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 844.979612] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.043385] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.095963] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.180529] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.252605] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.324366] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.408690] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.462179] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.496458] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.531143] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.560323] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.597061] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.631636] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.690068] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.720649] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.765440] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.803828] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.834629] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.879173] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 845.956755] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.024816] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.101553] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.164615] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.236621] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.283993] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.325486] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.411090] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.512802] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.611627] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.698445] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.752874] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.813464] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.816190] Lustre: lustre-OST0000-osc-ffff9d858c4eb000: disconnect after 21s idle [ 846.880489] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.935066] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 846.988340] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.054088] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.128114] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.187463] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.243409] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.301168] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.343092] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.390990] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.454564] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.517658] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.568914] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.615562] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.697586] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.807503] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.884741] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 847.992611] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.065702] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.137421] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.209189] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.297950] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.375933] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.458860] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.511575] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.604080] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.688115] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.777947] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.844226] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.920425] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 848.990921] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 849.062681] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 849.132270] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 849.217314] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 849.289575] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 849.365164] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 849.461458] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 849.597766] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 849.662300] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 849.772485] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 849.894249] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.011214] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.114211] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.219190] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.287040] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.384860] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.440610] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.525963] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.610322] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.661327] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.741957] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.801366] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.883157] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 850.966182] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 851.036587] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 851.113546] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 851.200531] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 851.281432] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 851.443265] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 851.501682] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 851.565475] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 851.668850] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 851.734496] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 851.796423] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 851.870443] rw_seq_cst_vs_d (29640): drop_caches: 3 [ 860.332595] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 17:22:32 (1787692952) [ 860.951976] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 861.118807] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 861.213238] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 861.338799] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 861.408896] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 861.539624] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 861.808959] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 861.871878] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 862.000566] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 862.098934] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 862.153798] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 862.318692] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 862.385739] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 862.582848] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 862.695905] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 862.904597] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 862.954296] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 863.046576] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 863.138730] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 863.268554] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 863.326443] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 863.459633] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 863.511203] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 863.729465] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 864.057151] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 864.255759] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 864.373933] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 864.586128] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 864.668327] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 864.762157] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 864.929042] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 865.209623] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 865.442171] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 865.663859] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 865.898980] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 866.217840] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 866.273992] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 866.562730] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 866.743407] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 867.051968] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 867.236438] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 867.327625] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 867.434784] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 867.534300] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 867.668157] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 867.835266] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 868.007829] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 868.373940] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 868.480854] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 868.630644] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 868.720634] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 868.911357] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 869.120408] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 869.296960] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 869.375810] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 869.614407] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 869.695933] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 869.818228] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 870.107362] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 870.218903] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 870.425191] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 870.780559] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 870.965839] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 871.321965] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 871.422296] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 871.654230] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 871.867139] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 871.997519] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 872.139490] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 872.282718] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 872.392783] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 872.416951] Lustre: lustre-OST0001-osc-ffff9d858c4eb000: disconnect after 20s idle [ 872.434767] Lustre: Skipped 1 previous similar message [ 872.605479] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 872.789708] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 873.138338] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 873.231080] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 873.339416] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 873.420771] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 873.487977] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 873.703481] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 873.992757] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 874.207089] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 874.291442] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 874.442871] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 874.566161] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 874.716480] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 874.806476] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 874.892262] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 875.031140] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 875.098745] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 875.232149] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 875.283247] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 875.389711] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 875.502693] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 875.663858] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 875.733931] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 876.031870] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 876.210683] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 876.322867] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 876.455578] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 876.572255] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 876.720613] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 876.884144] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 876.951676] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 877.022749] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 877.219899] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 877.384400] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 877.539582] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 877.631575] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 877.755604] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 877.840940] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 877.971819] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 878.236897] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 878.415365] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 878.609025] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 878.759764] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 878.919318] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 879.049742] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 879.113479] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 879.255612] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 879.350834] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 879.525670] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 879.596579] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 879.679719] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 879.759825] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 879.808858] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 879.915074] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 880.107743] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 880.226677] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 880.487141] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 880.603738] rw_seq_cst_vs_d (30216): drop_caches: 3 [ 890.008357] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 17:23:01 (1787692981) [ 898.053316] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 17:23:09 (1787692989) [ 906.074326] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 17:23:17 (1787692997) [ 951.165462] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 17:24:02 (1787693042) [ 952.033525] bash (32667): drop_caches: 3 [ 955.380363] bash (32667): drop_caches: 3 [ 958.618192] bash (32667): drop_caches: 3 [ 962.383878] bash (32667): drop_caches: 3 [ 965.883353] bash (32667): drop_caches: 3 [ 969.025948] bash (32667): drop_caches: 3 [ 972.268512] bash (32667): drop_caches: 3 [ 975.462452] bash (32667): drop_caches: 3 [ 978.648068] bash (32667): drop_caches: 3 [ 981.841424] bash (32667): drop_caches: 3 [ 985.033122] bash (32667): drop_caches: 3 [ 988.167164] bash (32667): drop_caches: 3 [ 991.305810] bash (32667): drop_caches: 3 [ 994.461712] bash (32667): drop_caches: 3 [ 997.611591] bash (32667): drop_caches: 3 [ 1000.761357] bash (32667): drop_caches: 3 [ 1003.900213] bash (32667): drop_caches: 3 [ 1007.112846] bash (32667): drop_caches: 3 [ 1012.153574] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 17:25:03 (1787693103) [ 1022.193235] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 17:25:13 (1787693113) [ 1137.966527] LustreError: lustre-OST0001-osc-ffff9d858c4eb000: operation ost_write to node 192.168.201.137@tcp failed: rc = -107 [ 1137.979063] Lustre: lustre-OST0001-osc-ffff9d858c4eb000: Connection to lustre-OST0001 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1138.048298] LustreError: lustre-OST0001-osc-ffff9d858c4eb000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1138.068434] Lustre: 2360:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.137@tcp:/lustre/fid: [0x200000401:0x6d:0x0]// may get corrupted (rc -5) [ 1138.081499] Lustre: lustre-OST0001-osc-ffff9d858c4eb000: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [ 1138.107853] LustreError: lustre-OST0000-osc-ffff9d8590d43000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1138.114625] Lustre: 2361:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.137@tcp:/lustre/fid: [0x200000401:0x6e:0x0]// may get corrupted (rc -5) [ 1155.277086] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 17:27:26 (1787693246) [ 1158.295646] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 1159.137716] Lustre: lustre-OST0001-osc-ffff9d858c4eb000: disconnect after 21s idle [ 1159.142847] Lustre: Skipped 1 previous similar message [ 1159.809344] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 17:27:31 (1787693251) [ 1169.479500] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 17:27:39 (1787693259) [ 1178.290830] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 17:27:49 (1787693269) [ 1248.272621] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 17:28:59 (1787693339) [ 1256.715474] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 17:29:08 (1787693348) [ 1265.889353] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 17:29:16 (1787693356) [ 1273.722214] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 17:29:25 (1787693365) [ 1275.716607] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1276.897573] Lustre: lustre-OST0000-osc-ffff9d8590d43000: disconnect after 20s idle [ 1276.916283] Lustre: Skipped 5 previous similar messages [ 1277.579341] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 17:29:29 (1787693369) [ 1285.443400] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 17:29:37 (1787693377) [ 1295.891758] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 17:29:47 (1787693387) [ 1303.992794] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1305.338075] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 17:29:57 (1787693397) [ 1314.734917] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 17:30:06 (1787693406) [ 1315.022400] Lustre: *** cfs_fail_loc=314, val=0*** [ 1316.068824] Lustre: *** cfs_fail_loc=314, val=0*** [ 1316.072381] Lustre: Skipped 2 previous similar messages [ 1323.212970] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 17:30:15 (1787693415) [ 1338.561081] Lustre: *** cfs_fail_loc=314, val=0*** [ 1343.482064] Lustre: lustre-OST0000-osc-ffff9d858c4eb000: Connection to lustre-OST0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1343.515370] Lustre: Skipped 1 previous similar message [ 1343.575143] LustreError: lustre-OST0000-osc-ffff9d858c4eb000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1343.619664] Lustre: lustre-OST0000-osc-ffff9d858c4eb000: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [ 1343.638843] Lustre: Skipped 1 previous similar message [ 1349.702992] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 17:30:40 (1787693440) [ 1350.220553] LustreError: 42568:0:(file.c:850:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1353.280189] LustreError: 42568:0:(file.c:850:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1361.056420] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 17:30:52 (1787693452) [ 1368.740204] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 17:31:00 (1787693460) [ 1377.446803] Lustre: DEBUG MARKER: == sanityn test 32b: lockless i/o ======================== 17:31:09 (1787693469) [ 1379.187429] Lustre: DEBUG MARKER: SKIP: sanityn test_32b max_nolock_bytes is removed >= 2.17.53 [ 1381.704566] Lustre: DEBUG MARKER: == sanityn test 32c: contention detection ================ 17:31:13 (1787693473) [ 1416.242465] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1418.193277] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 17:31:49 (1787693509) [ 1419.946678] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1420.269351] Lustre: lustre-OST0001-osc-ffff9d8590d43000: disconnect after 23s idle [ 1420.271886] Lustre: Skipped 1 previous similar message [ 1421.767619] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 17:31:53 (1787693513) [ 1423.983570] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1426.210924] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 17:31:57 (1787693517) [ 1427.891988] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1430.112555] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 17:32:01 (1787693521) [ 1431.649359] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1433.636444] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 17:32:05 (1787693525) [ 1490.914923] Lustre: lustre-OST0000-osc-ffff9d8590d43000: Connection to lustre-OST0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1490.937343] LustreError: lustre-OST0000-osc-ffff9d8590d43000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1490.954136] LustreError: lustre-OST0000-osc-ffff9d858c4eb000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1490.954856] Lustre: lustre-OST0000-osc-ffff9d8590d43000: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [ 1490.970400] Lustre: Skipped 1 previous similar message [ 1506.191107] Lustre: lustre-OST0001-osc-ffff9d8590d43000: Connection to lustre-OST0001 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1506.208611] Lustre: Skipped 1 previous similar message [ 1506.239441] LustreError: lustre-OST0001-osc-ffff9d8590d43000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1506.264722] Lustre: lustre-OST0001-osc-ffff9d8590d43000: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [ 1527.619697] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d858c4eb000.ost_server_uuid 50 [ 1529.617615] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d858c4eb000.ost_server_uuid in IDLE state after 0 sec [ 1536.174188] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d858c4eb000.ost_server_uuid 50 [ 1539.058980] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d858c4eb000.ost_server_uuid in FULL state after 0 sec [ 1546.546097] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d858c4eb000.ost_server_uuid 50 [ 1547.742375] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d858c4eb000.ost_server_uuid in IDLE state after 0 sec [ 1554.469879] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d858c4eb000.ost_server_uuid 50 [ 1556.651915] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d858c4eb000.ost_server_uuid in FULL state after 0 sec [ 1570.829163] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d858c4eb000.ost_server_uuid 50 [ 1573.353985] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d858c4eb000.ost_server_uuid in IDLE state after 0 sec [ 1581.645674] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d858c4eb000.ost_server_uuid 50 [ 1583.996025] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d858c4eb000.ost_server_uuid in FULL state after 0 sec [ 1586.028263] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 17:34:37 (1787693677) [ 1588.220709] Lustre: DEBUG MARKER: Race attempt 0 [ 1591.424364] Lustre: DEBUG MARKER: Wait for 50460 50472 for 60 sec... [ 1659.523077] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 17:35:50 (1787693750) [ 1667.944563] Lustre: DEBUG MARKER: start test - cycle (0) [ 1695.132910] Lustre: DEBUG MARKER: start test - cycle (1) [ 1701.856647] Lustre: lustre-OST0000-osc-ffff9d858c4eb000: disconnect after 24s idle [ 1701.866292] Lustre: Skipped 6 previous similar messages [ 1715.839232] Lustre: DEBUG MARKER: start test - cycle (2) [ 1735.825592] Lustre: DEBUG MARKER: start test - cycle (3) [ 1763.083870] Lustre: DEBUG MARKER: start test - cycle (4) [ 1794.127781] Lustre: DEBUG MARKER: start test - cycle (5) [ 1825.034883] Lustre: DEBUG MARKER: start test - cycle (6) [ 1855.699572] Lustre: DEBUG MARKER: start test - cycle (7) [ 1885.037802] Lustre: DEBUG MARKER: start test - cycle (8) [ 1911.441269] Lustre: DEBUG MARKER: start test - cycle (9) [ 1942.354525] Lustre: DEBUG MARKER: start test - cycle (10) [ 1980.271183] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 17:41:11 (1787694071) [ 2070.673396] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 17:42:42 (1787694162) [ 2078.650957] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 17:42:50 (1787694170) [ 2087.383852] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 17:42:58 (1787694178) [ 2096.051338] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 17:43:07 (1787694187) [ 2096.588677] Lustre: *** cfs_fail_loc=411, val=0*** [ 2103.786394] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2105.355753] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 17:43:17 (1787694197) [ 2123.839434] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 17:43:35 (1787694215) [ 2140.092595] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 17:43:51 (1787694231) [ 2156.673802] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 17:44:08 (1787694248) [ 2172.527195] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 17:44:24 (1787694264) [ 2188.429405] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 17:44:39 (1787694279) [ 2202.187210] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 17:44:54 (1787694294) [ 2215.019856] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 17:45:06 (1787694306) [ 2233.538076] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 17:45:24 (1787694324) [ 2234.337591] Lustre: lustre-OST0000-osc-ffff9d8590d43000: disconnect after 22s idle [ 2234.342748] Lustre: Skipped 21 previous similar messages [ 2247.325542] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 17:45:39 (1787694339) [ 2262.343921] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 17:45:53 (1787694353) [ 2276.886467] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 17:46:08 (1787694368) [ 2293.889591] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 17:46:25 (1787694385) [ 2910.176966] Lustre: lustre-OST0000-osc-ffff9d858c4eb000: disconnect after 23s idle [ 2910.184134] Lustre: Skipped 5 previous similar messages [ 3381.603624] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 18:04:33 (1787695473) [ 3396.935379] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 18:04:48 (1787695488) [ 3410.733550] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 18:05:02 (1787695502) [ 3425.243702] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 18:05:16 (1787695516) [ 3439.282180] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 18:05:30 (1787695530) [ 3454.073504] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 18:05:45 (1787695545) [ 3468.106397] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 18:05:59 (1787695559) [ 3483.094112] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 18:06:14 (1787695574) [ 3498.528984] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 18:06:29 (1787695589) [ 3571.889945] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 18:07:43 (1787695663) [ 3587.787993] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 18:07:59 (1787695679) [ 3604.106477] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 18:08:15 (1787695695) [ 3619.370935] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 18:08:30 (1787695710) [ 3621.856220] Lustre: lustre-OST0001-osc-ffff9d8590d43000: disconnect after 22s idle [ 3621.859417] Lustre: Skipped 4 previous similar messages [ 3638.240106] Lustre: 2362:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787695715/real 1787695715] req@ffff9d85babd5880 x1874531134120064/t0(0) o2->lustre-OST0001-osc-ffff9d8590d43000@192.168.201.137@tcp:28/4 lens 440/432 e 0 to 1 dl 1787695731 ref 1 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'touch.0' uid:0 gid:0 projid:0 [ 3638.246561] Lustre: lustre-OST0001-osc-ffff9d8590d43000: Connection to lustre-OST0001 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3643.360137] Lustre: 2362:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787695720/real 1787695720] req@ffff9d85b9ce1f80 x1874531134120576/t0(0) o400->lustre-OST0001-osc-ffff9d8590d43000@192.168.201.137@tcp:28/4 lens 224/224 e 0 to 1 dl 1787695736 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'kworker.0' uid:0 gid:0 projid:4294967295 [ 3643.367868] Lustre: lustre-OST0000-osc-ffff9d858c4eb000: Connection to lustre-OST0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3643.367959] LustreError: MGC192.168.201.137@tcp: Connection to MGS (at 192.168.201.137@tcp) was lost; in progress operations using this service will fail [ 3643.371902] Lustre: Skipped 1 previous similar message [ 3644.384095] Lustre: 2363:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787695720/real 1787695720] req@ffff9d85b9ce3480 x1874531134120704/t0(0) o400->lustre-MDT0000-mdc-ffff9d858c4eb000@192.168.201.137@tcp:12/10 lens 224/224 e 0 to 1 dl 1787695737 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'kworker.0' uid:0 gid:0 projid:4294967295 [ 3644.384191] Lustre: lustre-MDT0000-mdc-ffff9d8590d43000: Connection to lustre-MDT0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3644.391218] Lustre: 2363:0:(client.c:2503:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 3644.398389] Lustre: Skipped 1 previous similar message [ 3648.480163] Lustre: 2363:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787695725/real 1787695725] req@ffff9d85babd4a80 x1874531134121472/t0(0) o400->lustre-OST0001-osc-ffff9d8590d43000@192.168.201.137@tcp:28/4 lens 224/224 e 0 to 1 dl 1787695741 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'kworker.0' uid:0 gid:0 projid:4294967295 [ 3648.486937] Lustre: 2363:0:(client.c:2503:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 3653.600125] Lustre: 2363:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787695730/real 1787695730] req@ffff9d85b9ce1c00 x1874531134122240/t0(0) o400->lustre-OST0000-osc-ffff9d8590d43000@192.168.201.137@tcp:28/4 lens 224/224 e 0 to 1 dl 1787695746 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'kworker.0' uid:0 gid:0 projid:4294967295 [ 3653.615334] Lustre: 2363:0:(client.c:2503:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 3810.272135] INFO: task touch:102020 blocked for more than 120 seconds. [ 3810.273669] Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 3810.275311] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 3810.277468] task:touch state:D stack:0 pid:102020 ppid:101776 flags:0x80004000 [ 3810.279408] Call Trace: [ 3810.280100] __schedule+0x351/0xcb0 [ 3810.280828] ? wait_for_completion+0xae/0x1e0 [ 3810.281690] schedule+0xc0/0x180 [ 3810.282477] schedule_timeout+0x126/0x190 [ 3810.283503] ? osc_setattr_async+0x150/0x380 [osc] [ 3810.284603] ? __prepare_to_swait+0x5b/0x90 [ 3810.285892] ? do_raw_spin_unlock+0x75/0x190 [ 3810.286960] wait_for_completion+0xf0/0x1e0 [ 3810.287985] osc_io_setattr_end+0x21b/0x320 [osc] [ 3810.289167] ? cl_io_start+0x65/0x190 [obdclass] [ 3810.290291] cl_io_end+0x5a/0x190 [obdclass] [ 3810.291460] ? lov_comp_index+0xa0/0xa0 [lov] [ 3810.292550] lov_io_end_wrapper+0x10f/0x120 [lov] [ 3810.293690] lov_io_call.isra.11+0x91/0x1c0 [lov] [ 3810.294706] lov_io_end+0xba/0x190 [lov] [ 3810.295619] cl_io_end+0x5a/0x190 [obdclass] [ 3810.296726] cl_io_loop+0xf7/0x2f0 [obdclass] [ 3810.297736] cl_setattr_ost+0x3b5/0x520 [lustre] [ 3810.299096] ll_setattr_raw+0x133f/0x1820 [lustre] [ 3810.300224] ? _raw_spin_unlock+0x12/0x30 [ 3810.301276] ? dput+0x1ff/0x300 [ 3810.302035] ? mntput+0x28/0x50 [ 3810.302851] ? terminate_walk+0x14e/0x170 [ 3810.303896] ll_setattr+0x72/0x240 [lustre] [ 3810.304902] notify_change+0x3f0/0x790 [ 3810.305785] ? __might_sleep+0x59/0xc0 [ 3810.306653] utimes_common.isra.2+0x14c/0x2f0 [ 3810.307713] ? slab_free_freelist_hook.isra.49+0x107/0x220 [ 3810.309024] ? kmem_cache_free+0x3a8/0x4d0 [ 3810.310075] do_utimes+0x1e8/0x280 [ 3810.310897] __x64_sys_utimensat+0xa6/0x130 [ 3810.312575] ? fput+0x17/0x20 [ 3810.313565] ? filp_close+0x72/0xa0 [ 3810.314284] do_syscall_64+0xc1/0x440 [ 3810.315023] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 3810.316751] RIP: 0033:0x7fab73db92f7 [ 3810.318239] Code: Unable to access opcode bytes at RIP 0x7fab73db92cd. [ 3810.321013] RSP: 002b:00007ffe343548a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000118 [ 3810.323207] RAX: ffffffffffffffda RBX: 00007ffe34356c7a RCX: 00007fab73db92f7 [ 3810.324513] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 3810.325873] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 3810.327191] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffff9c [ 3810.328524] R13: 0000000000000000 R14: 00007ffe34356c7a R15: 00007fab74057374 [ 3933.152119] INFO: task touch:102020 blocked for more than 120 seconds. [ 3933.153849] Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 3933.155370] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 3933.157013] task:touch state:D stack:0 pid:102020 ppid:101776 flags:0x80004000 [ 3933.158806] Call Trace: [ 3933.159465] __schedule+0x351/0xcb0 [ 3933.160348] ? wait_for_completion+0xae/0x1e0 [ 3933.161339] schedule+0xc0/0x180 [ 3933.162084] schedule_timeout+0x126/0x190 [ 3933.162884] ? osc_setattr_async+0x150/0x380 [osc] [ 3933.163926] ? __prepare_to_swait+0x5b/0x90 [ 3933.164810] ? do_raw_spin_unlock+0x75/0x190 [ 3933.166248] wait_for_completion+0xf0/0x1e0 [ 3933.168250] osc_io_setattr_end+0x21b/0x320 [osc] [ 3933.169190] ? cl_io_start+0x65/0x190 [obdclass] [ 3933.170207] cl_io_end+0x5a/0x190 [obdclass] [ 3933.171269] ? lov_comp_index+0xa0/0xa0 [lov] [ 3933.172241] lov_io_end_wrapper+0x10f/0x120 [lov] [ 3933.173159] lov_io_call.isra.11+0x91/0x1c0 [lov] [ 3933.174178] lov_io_end+0xba/0x190 [lov] [ 3933.174917] cl_io_end+0x5a/0x190 [obdclass] [ 3933.175859] cl_io_loop+0xf7/0x2f0 [obdclass] [ 3933.176987] cl_setattr_ost+0x3b5/0x520 [lustre] [ 3933.178306] ll_setattr_raw+0x133f/0x1820 [lustre] [ 3933.179225] ? _raw_spin_unlock+0x12/0x30 [ 3933.180020] ? dput+0x1ff/0x300 [ 3933.180719] ? mntput+0x28/0x50 [ 3933.181296] ? terminate_walk+0x14e/0x170 [ 3933.182085] ll_setattr+0x72/0x240 [lustre] [ 3933.182960] notify_change+0x3f0/0x790 [ 3933.183705] ? __might_sleep+0x59/0xc0 [ 3933.184420] utimes_common.isra.2+0x14c/0x2f0 [ 3933.185406] ? slab_free_freelist_hook.isra.49+0x107/0x220 [ 3933.186473] ? kmem_cache_free+0x3a8/0x4d0 [ 3933.187261] do_utimes+0x1e8/0x280 [ 3933.187948] __x64_sys_utimensat+0xa6/0x130 [ 3933.188801] ? fput+0x17/0x20 [ 3933.189335] ? filp_close+0x72/0xa0 [ 3933.190023] do_syscall_64+0xc1/0x440 [ 3933.190802] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 3933.191839] RIP: 0033:0x7fab73db92f7 [ 3933.192759] Code: Unable to access opcode bytes at RIP 0x7fab73db92cd. [ 3933.194008] RSP: 002b:00007ffe343548a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000118 [ 3933.195385] RAX: ffffffffffffffda RBX: 00007ffe34356c7a RCX: 00007fab73db92f7 [ 3933.196954] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 3933.198449] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 3933.199785] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffff9c [ 3933.201155] R13: 0000000000000000 R14: 00007ffe34356c7a R15: 00007fab74057374 [ 4056.032137] INFO: task touch:102020 blocked for more than 120 seconds. [ 4056.034176] Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 4056.036121] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 4056.037938] task:touch state:D stack:0 pid:102020 ppid:101776 flags:0x80004000 [ 4056.039701] Call Trace: [ 4056.040217] __schedule+0x351/0xcb0 [ 4056.040809] ? wait_for_completion+0xae/0x1e0 [ 4056.041588] schedule+0xc0/0x180 [ 4056.042084] schedule_timeout+0x126/0x190 [ 4056.042762] ? osc_setattr_async+0x150/0x380 [osc] [ 4056.043680] ? __prepare_to_swait+0x5b/0x90 [ 4056.044561] ? do_raw_spin_unlock+0x75/0x190 [ 4056.045573] wait_for_completion+0xf0/0x1e0 [ 4056.046422] osc_io_setattr_end+0x21b/0x320 [osc] [ 4056.047503] ? cl_io_start+0x65/0x190 [obdclass] [ 4056.048528] cl_io_end+0x5a/0x190 [obdclass] [ 4056.049549] ? lov_comp_index+0xa0/0xa0 [lov] [ 4056.050470] lov_io_end_wrapper+0x10f/0x120 [lov] [ 4056.051448] lov_io_call.isra.11+0x91/0x1c0 [lov] [ 4056.052553] lov_io_end+0xba/0x190 [lov] [ 4056.053362] cl_io_end+0x5a/0x190 [obdclass] [ 4056.054334] cl_io_loop+0xf7/0x2f0 [obdclass] [ 4056.056024] cl_setattr_ost+0x3b5/0x520 [lustre] [ 4056.057031] ll_setattr_raw+0x133f/0x1820 [lustre] [ 4056.058067] ? _raw_spin_unlock+0x12/0x30 [ 4056.058861] ? dput+0x1ff/0x300 [ 4056.059715] ? mntput+0x28/0x50 [ 4056.060216] ? terminate_walk+0x14e/0x170 [ 4056.060916] ll_setattr+0x72/0x240 [lustre] [ 4056.061693] notify_change+0x3f0/0x790 [ 4056.062294] ? __might_sleep+0x59/0xc0 [ 4056.062904] utimes_common.isra.2+0x14c/0x2f0 [ 4056.063611] ? slab_free_freelist_hook.isra.49+0x107/0x220 [ 4056.064544] ? kmem_cache_free+0x3a8/0x4d0 [ 4056.065212] do_utimes+0x1e8/0x280 [ 4056.065822] __x64_sys_utimensat+0xa6/0x130 [ 4056.066459] ? fput+0x17/0x20 [ 4056.066939] ? filp_close+0x72/0xa0 [ 4056.067600] do_syscall_64+0xc1/0x440 [ 4056.068185] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 4056.069066] RIP: 0033:0x7fab73db92f7 [ 4056.069721] Code: Unable to access opcode bytes at RIP 0x7fab73db92cd. [ 4056.070785] RSP: 002b:00007ffe343548a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000118 [ 4056.072027] RAX: ffffffffffffffda RBX: 00007ffe34356c7a RCX: 00007fab73db92f7 [ 4056.073481] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 4056.074978] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 4056.076087] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffff9c [ 4056.077239] R13: 0000000000000000 R14: 00007ffe34356c7a R15: 00007fab74057374 [ 4178.912110] INFO: task touch:102020 blocked for more than 120 seconds. [ 4178.913973] Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 4178.915810] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 4178.917603] task:touch state:D stack:0 pid:102020 ppid:101776 flags:0x80004000 [ 4178.919554] Call Trace: [ 4178.920134] __schedule+0x351/0xcb0 [ 4178.921054] ? wait_for_completion+0xae/0x1e0 [ 4178.922057] schedule+0xc0/0x180 [ 4178.922963] schedule_timeout+0x126/0x190 [ 4178.923824] ? osc_setattr_async+0x150/0x380 [osc] [ 4178.924991] ? __prepare_to_swait+0x5b/0x90 [ 4178.925963] ? do_raw_spin_unlock+0x75/0x190 [ 4178.926925] wait_for_completion+0xf0/0x1e0 [ 4178.927881] osc_io_setattr_end+0x21b/0x320 [osc] [ 4178.928982] ? cl_io_start+0x65/0x190 [obdclass] [ 4178.930142] cl_io_end+0x5a/0x190 [obdclass] [ 4178.931226] ? lov_comp_index+0xa0/0xa0 [lov] [ 4178.932175] lov_io_end_wrapper+0x10f/0x120 [lov] [ 4178.933255] lov_io_call.isra.11+0x91/0x1c0 [lov] [ 4178.934474] lov_io_end+0xba/0x190 [lov] [ 4178.935375] cl_io_end+0x5a/0x190 [obdclass] [ 4178.936490] cl_io_loop+0xf7/0x2f0 [obdclass] [ 4178.937783] cl_setattr_ost+0x3b5/0x520 [lustre] [ 4178.938954] ll_setattr_raw+0x133f/0x1820 [lustre] [ 4178.940109] ? _raw_spin_unlock+0x12/0x30 [ 4178.941111] ? dput+0x1ff/0x300 [ 4178.941942] ? mntput+0x28/0x50 [ 4178.942638] ? terminate_walk+0x14e/0x170 [ 4178.943541] ll_setattr+0x72/0x240 [lustre] [ 4178.944535] notify_change+0x3f0/0x790 [ 4178.945450] ? __might_sleep+0x59/0xc0 [ 4178.946219] utimes_common.isra.2+0x14c/0x2f0 [ 4178.947208] ? slab_free_freelist_hook.isra.49+0x107/0x220 [ 4178.948352] ? kmem_cache_free+0x3a8/0x4d0 [ 4178.949205] do_utimes+0x1e8/0x280 [ 4178.949867] __x64_sys_utimensat+0xa6/0x130 [ 4178.950680] ? fput+0x17/0x20 [ 4178.951275] ? filp_close+0x72/0xa0 [ 4178.952875] do_syscall_64+0xc1/0x440 [ 4178.954826] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 4178.955947] RIP: 0033:0x7fab73db92f7 [ 4178.956827] Code: Unable to access opcode bytes at RIP 0x7fab73db92cd. [ 4178.958222] RSP: 002b:00007ffe343548a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000118 [ 4178.959781] RAX: ffffffffffffffda RBX: 00007ffe34356c7a RCX: 00007fab73db92f7 [ 4178.961207] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 4178.962723] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 4178.964181] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffff9c [ 4178.965565] R13: 0000000000000000 R14: 00007ffe34356c7a R15: 00007fab74057374 [ 4301.792118] INFO: task touch:102020 blocked for more than 120 seconds. [ 4301.793831] Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 4301.795543] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 4301.797417] task:touch state:D stack:0 pid:102020 ppid:101776 flags:0x80004000 [ 4301.799363] Call Trace: [ 4301.799969] __schedule+0x351/0xcb0 [ 4301.800849] ? wait_for_completion+0xae/0x1e0 [ 4301.802016] schedule+0xc0/0x180 [ 4301.802803] schedule_timeout+0x126/0x190 [ 4301.803684] ? osc_setattr_async+0x150/0x380 [osc] [ 4301.804763] ? __prepare_to_swait+0x5b/0x90 [ 4301.805620] ? do_raw_spin_unlock+0x75/0x190 [ 4301.806533] wait_for_completion+0xf0/0x1e0 [ 4301.807410] osc_io_setattr_end+0x21b/0x320 [osc] [ 4301.808458] ? cl_io_start+0x65/0x190 [obdclass] [ 4301.809591] cl_io_end+0x5a/0x190 [obdclass] [ 4301.810609] ? lov_comp_index+0xa0/0xa0 [lov] [ 4301.811688] lov_io_end_wrapper+0x10f/0x120 [lov] [ 4301.812859] lov_io_call.isra.11+0x91/0x1c0 [lov] [ 4301.813969] lov_io_end+0xba/0x190 [lov] [ 4301.814912] cl_io_end+0x5a/0x190 [obdclass] [ 4301.816039] cl_io_loop+0xf7/0x2f0 [obdclass] [ 4301.817193] cl_setattr_ost+0x3b5/0x520 [lustre] [ 4301.818226] ll_setattr_raw+0x133f/0x1820 [lustre] [ 4301.819365] ? _raw_spin_unlock+0x12/0x30 [ 4301.820302] ? dput+0x1ff/0x300 [ 4301.821048] ? mntput+0x28/0x50 [ 4301.821694] ? terminate_walk+0x14e/0x170 [ 4301.822602] ll_setattr+0x72/0x240 [lustre] [ 4301.823639] notify_change+0x3f0/0x790 [ 4301.824463] ? __might_sleep+0x59/0xc0 [ 4301.825400] utimes_common.isra.2+0x14c/0x2f0 [ 4301.827329] ? slab_free_freelist_hook.isra.49+0x107/0x220 [ 4301.828845] ? kmem_cache_free+0x3a8/0x4d0 [ 4301.829992] do_utimes+0x1e8/0x280 [ 4301.831388] __x64_sys_utimensat+0xa6/0x130 [ 4301.832594] ? fput+0x17/0x20 [ 4301.833297] ? filp_close+0x72/0xa0 [ 4301.833994] do_syscall_64+0xc1/0x440 [ 4301.834764] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 4301.835743] RIP: 0033:0x7fab73db92f7 [ 4301.836511] Code: Unable to access opcode bytes at RIP 0x7fab73db92cd. [ 4301.838029] RSP: 002b:00007ffe343548a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000118 [ 4301.839561] RAX: ffffffffffffffda RBX: 00007ffe34356c7a RCX: 00007fab73db92f7 [ 4301.841067] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 4301.842475] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 4301.844029] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffff9c [ 4301.845504] R13: 0000000000000000 R14: 00007ffe34356c7a R15: 00007fab74057374 [ 4424.672179] INFO: task touch:102020 blocked for more than 120 seconds. [ 4424.675219] Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 4424.676650] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 4424.678401] task:touch state:D stack:0 pid:102020 ppid:101776 flags:0x80004000 [ 4424.680191] Call Trace: [ 4424.680694] __schedule+0x351/0xcb0 [ 4424.681410] ? wait_for_completion+0xae/0x1e0 [ 4424.682227] schedule+0xc0/0x180 [ 4424.682942] schedule_timeout+0x126/0x190 [ 4424.683747] ? osc_setattr_async+0x150/0x380 [osc] [ 4424.684755] ? __prepare_to_swait+0x5b/0x90 [ 4424.685583] ? do_raw_spin_unlock+0x75/0x190 [ 4424.686443] wait_for_completion+0xf0/0x1e0 [ 4424.687282] osc_io_setattr_end+0x21b/0x320 [osc] [ 4424.688198] ? cl_io_start+0x65/0x190 [obdclass] [ 4424.689209] cl_io_end+0x5a/0x190 [obdclass] [ 4424.690114] ? lov_comp_index+0xa0/0xa0 [lov] [ 4424.690927] lov_io_end_wrapper+0x10f/0x120 [lov] [ 4424.691882] lov_io_call.isra.11+0x91/0x1c0 [lov] [ 4424.692914] lov_io_end+0xba/0x190 [lov] [ 4424.693680] cl_io_end+0x5a/0x190 [obdclass] [ 4424.694593] cl_io_loop+0xf7/0x2f0 [obdclass] [ 4424.695576] cl_setattr_ost+0x3b5/0x520 [lustre] [ 4424.696565] ll_setattr_raw+0x133f/0x1820 [lustre] [ 4424.697559] ? _raw_spin_unlock+0x12/0x30 [ 4424.698374] ? dput+0x1ff/0x300 [ 4424.699193] ? mntput+0x28/0x50 [ 4424.700504] ? terminate_walk+0x14e/0x170 [ 4424.701452] ll_setattr+0x72/0x240 [lustre] [ 4424.702328] notify_change+0x3f0/0x790 [ 4424.703054] ? __might_sleep+0x59/0xc0 [ 4424.703806] utimes_common.isra.2+0x14c/0x2f0 [ 4424.704615] ? slab_free_freelist_hook.isra.49+0x107/0x220 [ 4424.705712] ? kmem_cache_free+0x3a8/0x4d0 [ 4424.706509] do_utimes+0x1e8/0x280 [ 4424.707180] __x64_sys_utimensat+0xa6/0x130 [ 4424.708064] ? fput+0x17/0x20 [ 4424.708626] ? filp_close+0x72/0xa0 [ 4424.709326] do_syscall_64+0xc1/0x440 [ 4424.709985] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 4424.711073] RIP: 0033:0x7fab73db92f7 [ 4424.711821] Code: Unable to access opcode bytes at RIP 0x7fab73db92cd. [ 4424.713022] RSP: 002b:00007ffe343548a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000118 [ 4424.714457] RAX: ffffffffffffffda RBX: 00007ffe34356c7a RCX: 00007fab73db92f7 [ 4424.715798] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 4424.717119] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 4424.718482] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffff9c [ 4424.719805] R13: 0000000000000000 R14: 00007ffe34356c7a R15: 00007fab74057374 [ 4547.552086] INFO: task touch:102020 blocked for more than 120 seconds. [ 4547.553583] Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 4547.555286] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 4547.556890] task:touch state:D stack:0 pid:102020 ppid:101776 flags:0x80004000 [ 4547.558869] Call Trace: [ 4547.559445] __schedule+0x351/0xcb0 [ 4547.560215] ? wait_for_completion+0xae/0x1e0 [ 4547.561171] schedule+0xc0/0x180 [ 4547.561854] schedule_timeout+0x126/0x190 [ 4547.562703] ? osc_setattr_async+0x150/0x380 [osc] [ 4547.563802] ? __prepare_to_swait+0x5b/0x90 [ 4547.564680] ? do_raw_spin_unlock+0x75/0x190 [ 4547.565622] wait_for_completion+0xf0/0x1e0 [ 4547.566505] osc_io_setattr_end+0x21b/0x320 [osc] [ 4547.567620] ? cl_io_start+0x65/0x190 [obdclass] [ 4547.568735] cl_io_end+0x5a/0x190 [obdclass] [ 4547.569754] ? lov_comp_index+0xa0/0xa0 [lov] [ 4547.570736] lov_io_end_wrapper+0x10f/0x120 [lov] [ 4547.571760] lov_io_call.isra.11+0x91/0x1c0 [lov] [ 4547.572845] lov_io_end+0xba/0x190 [lov] [ 4547.573901] cl_io_end+0x5a/0x190 [obdclass] [ 4547.574981] cl_io_loop+0xf7/0x2f0 [obdclass] [ 4547.576101] cl_setattr_ost+0x3b5/0x520 [lustre] [ 4547.577254] ll_setattr_raw+0x133f/0x1820 [lustre] [ 4547.578418] ? _raw_spin_unlock+0x12/0x30 [ 4547.579340] ? dput+0x1ff/0x300 [ 4547.579970] ? mntput+0x28/0x50 [ 4547.580721] ? terminate_walk+0x14e/0x170 [ 4547.581644] ll_setattr+0x72/0x240 [lustre] [ 4547.582659] notify_change+0x3f0/0x790 [ 4547.583493] ? __might_sleep+0x59/0xc0 [ 4547.584339] utimes_common.isra.2+0x14c/0x2f0 [ 4547.585349] ? slab_free_freelist_hook.isra.49+0x107/0x220 [ 4547.586590] ? kmem_cache_free+0x3a8/0x4d0 [ 4547.587510] do_utimes+0x1e8/0x280 [ 4547.588305] __x64_sys_utimensat+0xa6/0x130 [ 4547.589213] ? fput+0x17/0x20 [ 4547.589864] ? filp_close+0x72/0xa0 [ 4547.590520] do_syscall_64+0xc1/0x440 [ 4547.591312] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 4547.592495] RIP: 0033:0x7fab73db92f7 [ 4547.593289] Code: Unable to access opcode bytes at RIP 0x7fab73db92cd. [ 4547.594797] RSP: 002b:00007ffe343548a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000118 [ 4547.597800] RAX: ffffffffffffffda RBX: 00007ffe34356c7a RCX: 00007fab73db92f7 [ 4547.599567] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 4547.601568] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 4547.603401] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffff9c [ 4547.604936] R13: 0000000000000000 R14: 00007ffe34356c7a R15: 00007fab74057374 [ 4670.432138] INFO: task touch:102020 blocked for more than 120 seconds. [ 4670.434662] Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 4670.437571] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 4670.440232] task:touch state:D stack:0 pid:102020 ppid:101776 flags:0x80004000 [ 4670.441978] Call Trace: [ 4670.442915] __schedule+0x351/0xcb0 [ 4670.444470] ? wait_for_completion+0xae/0x1e0 [ 4670.446132] schedule+0xc0/0x180 [ 4670.446706] schedule_timeout+0x126/0x190 [ 4670.447907] ? osc_setattr_async+0x150/0x380 [osc] [ 4670.449433] ? __prepare_to_swait+0x5b/0x90 [ 4670.450307] ? do_raw_spin_unlock+0x75/0x190 [ 4670.451249] wait_for_completion+0xf0/0x1e0 [ 4670.452131] osc_io_setattr_end+0x21b/0x320 [osc] [ 4670.453298] ? cl_io_start+0x65/0x190 [obdclass] [ 4670.454375] cl_io_end+0x5a/0x190 [obdclass] [ 4670.455367] ? lov_comp_index+0xa0/0xa0 [lov] [ 4670.456385] lov_io_end_wrapper+0x10f/0x120 [lov] [ 4670.457463] lov_io_call.isra.11+0x91/0x1c0 [lov] [ 4670.458346] lov_io_end+0xba/0x190 [lov] [ 4670.459181] cl_io_end+0x5a/0x190 [obdclass] [ 4670.460119] cl_io_loop+0xf7/0x2f0 [obdclass] [ 4670.461186] cl_setattr_ost+0x3b5/0x520 [lustre] [ 4670.462262] ll_setattr_raw+0x133f/0x1820 [lustre] [ 4670.463321] ? _raw_spin_unlock+0x12/0x30 [ 4670.464478] ? dput+0x1ff/0x300 [ 4670.466088] ? mntput+0x28/0x50 [ 4670.466862] ? terminate_walk+0x14e/0x170 [ 4670.468031] ll_setattr+0x72/0x240 [lustre] [ 4670.469204] notify_change+0x3f0/0x790 [ 4670.470202] ? __might_sleep+0x59/0xc0 [ 4670.471094] utimes_common.isra.2+0x14c/0x2f0 [ 4670.471998] ? slab_free_freelist_hook.isra.49+0x107/0x220 [ 4670.473249] ? kmem_cache_free+0x3a8/0x4d0 [ 4670.474210] do_utimes+0x1e8/0x280 [ 4670.475061] __x64_sys_utimensat+0xa6/0x130 [ 4670.475969] ? fput+0x17/0x20 [ 4670.476641] ? filp_close+0x72/0xa0 [ 4670.477489] do_syscall_64+0xc1/0x440 [ 4670.478340] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 4670.479497] RIP: 0033:0x7fab73db92f7 [ 4670.480377] Code: Unable to access opcode bytes at RIP 0x7fab73db92cd. [ 4670.482025] RSP: 002b:00007ffe343548a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000118 [ 4670.483652] RAX: ffffffffffffffda RBX: 00007ffe34356c7a RCX: 00007fab73db92f7 [ 4670.485237] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 4670.486870] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 4670.488497] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffff9c [ 4670.490061] R13: 0000000000000000 R14: 00007ffe34356c7a R15: 00007fab74057374 [ 4793.312177] INFO: task touch:102020 blocked for more than 120 seconds. [ 4793.313790] Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 4793.315309] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 4793.317163] task:touch state:D stack:0 pid:102020 ppid:101776 flags:0x80004000 [ 4793.318871] Call Trace: [ 4793.319422] __schedule+0x351/0xcb0 [ 4793.320191] ? wait_for_completion+0xae/0x1e0 [ 4793.321238] schedule+0xc0/0x180 [ 4793.322057] schedule_timeout+0x126/0x190 [ 4793.322863] ? osc_setattr_async+0x150/0x380 [osc] [ 4793.323878] ? __prepare_to_swait+0x5b/0x90 [ 4793.324759] ? do_raw_spin_unlock+0x75/0x190 [ 4793.325682] wait_for_completion+0xf0/0x1e0 [ 4793.326602] osc_io_setattr_end+0x21b/0x320 [osc] [ 4793.327742] ? cl_io_start+0x65/0x190 [obdclass] [ 4793.328962] cl_io_end+0x5a/0x190 [obdclass] [ 4793.330093] ? lov_comp_index+0xa0/0xa0 [lov] [ 4793.331272] lov_io_end_wrapper+0x10f/0x120 [lov] [ 4793.332673] lov_io_call.isra.11+0x91/0x1c0 [lov] [ 4793.334557] lov_io_end+0xba/0x190 [lov] [ 4793.335867] cl_io_end+0x5a/0x190 [obdclass] [ 4793.337648] cl_io_loop+0xf7/0x2f0 [obdclass] [ 4793.338957] cl_setattr_ost+0x3b5/0x520 [lustre] [ 4793.340621] ll_setattr_raw+0x133f/0x1820 [lustre] [ 4793.342646] ? _raw_spin_unlock+0x12/0x30 [ 4793.344137] ? dput+0x1ff/0x300 [ 4793.345189] ? mntput+0x28/0x50 [ 4793.346253] ? terminate_walk+0x14e/0x170 [ 4793.347510] ll_setattr+0x72/0x240 [lustre] [ 4793.349045] notify_change+0x3f0/0x790 [ 4793.350443] ? __might_sleep+0x59/0xc0 [ 4793.351695] utimes_common.isra.2+0x14c/0x2f0 [ 4793.352758] ? slab_free_freelist_hook.isra.49+0x107/0x220 [ 4793.354159] ? kmem_cache_free+0x3a8/0x4d0 [ 4793.355024] do_utimes+0x1e8/0x280 [ 4793.356043] __x64_sys_utimensat+0xa6/0x130 [ 4793.357586] ? fput+0x17/0x20 [ 4793.358291] ? filp_close+0x72/0xa0 [ 4793.359040] do_syscall_64+0xc1/0x440 [ 4793.359849] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 4793.360852] RIP: 0033:0x7fab73db92f7 [ 4793.361561] Code: Unable to access opcode bytes at RIP 0x7fab73db92cd. [ 4793.362860] RSP: 002b:00007ffe343548a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000118 [ 4793.364354] RAX: ffffffffffffffda RBX: 00007ffe34356c7a RCX: 00007fab73db92f7 [ 4793.365872] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 4793.367321] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 4793.369288] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffff9c [ 4793.371028] R13: 0000000000000000 R14: 00007ffe34356c7a R15: 00007fab74057374 [ 4916.192161] INFO: task touch:102020 blocked for more than 120 seconds. [ 4916.194647] Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 4916.197254] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 4916.199937] task:touch state:D stack:0 pid:102020 ppid:101776 flags:0x80004000 [ 4916.202668] Call Trace: [ 4916.203521] __schedule+0x351/0xcb0 [ 4916.204852] ? wait_for_completion+0xae/0x1e0 [ 4916.206410] schedule+0xc0/0x180 [ 4916.207505] schedule_timeout+0x126/0x190 [ 4916.208921] ? osc_setattr_async+0x150/0x380 [osc] [ 4916.210658] ? __prepare_to_swait+0x5b/0x90 [ 4916.212081] ? do_raw_spin_unlock+0x75/0x190 [ 4916.213533] wait_for_completion+0xf0/0x1e0 [ 4916.214939] osc_io_setattr_end+0x21b/0x320 [osc] [ 4916.216563] ? cl_io_start+0x65/0x190 [obdclass] [ 4916.218320] cl_io_end+0x5a/0x190 [obdclass] [ 4916.219936] ? lov_comp_index+0xa0/0xa0 [lov] [ 4916.221474] lov_io_end_wrapper+0x10f/0x120 [lov] [ 4916.223111] lov_io_call.isra.11+0x91/0x1c0 [lov] [ 4916.224708] lov_io_end+0xba/0x190 [lov] [ 4916.226088] cl_io_end+0x5a/0x190 [obdclass] [ 4916.227740] cl_io_loop+0xf7/0x2f0 [obdclass] [ 4916.229388] cl_setattr_ost+0x3b5/0x520 [lustre] [ 4916.230974] ll_setattr_raw+0x133f/0x1820 [lustre] [ 4916.232886] ? _raw_spin_unlock+0x12/0x30 [ 4916.234212] ? dput+0x1ff/0x300 [ 4916.235137] ? mntput+0x28/0x50 [ 4916.236346] ? terminate_walk+0x14e/0x170 [ 4916.238863] ll_setattr+0x72/0x240 [lustre] [ 4916.241136] notify_change+0x3f0/0x790 [ 4916.243312] ? __might_sleep+0x59/0xc0 [ 4916.245305] utimes_common.isra.2+0x14c/0x2f0 [ 4916.247609] ? slab_free_freelist_hook.isra.49+0x107/0x220 [ 4916.250288] ? kmem_cache_free+0x3a8/0x4d0 [ 4916.252264] do_utimes+0x1e8/0x280 [ 4916.253988] __x64_sys_utimensat+0xa6/0x130 [ 4916.255974] ? fput+0x17/0x20 [ 4916.257169] ? filp_close+0x72/0xa0 [ 4916.258217] do_syscall_64+0xc1/0x440 [ 4916.259300] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 4916.260861] RIP: 0033:0x7fab73db92f7 [ 4916.261748] Code: Unable to access opcode bytes at RIP 0x7fab73db92cd. [ 4916.263397] RSP: 002b:00007ffe343548a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000118 [ 4916.265129] RAX: ffffffffffffffda RBX: 00007ffe34356c7a RCX: 00007fab73db92f7 [ 4916.267452] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 4916.269833] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 4916.272097] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffff9c [ 4916.274367] R13: 0000000000000000 R14: 00007ffe34356c7a R15: 00007fab74057374