My Oracle Support Banner

NFS share hangs on NFS clients when tcp_timestamps disabled (Doc ID 2286691.1)

Last updated on FEBRUARY 02, 2019

Applies to:

Linux OS - Version Oracle Linux 4.4 to Oracle Linux 7.3 [Release OL4U4 to OL7U3]
Oracle Cloud Infrastructure - Version N/A and later
Information in this document applies to any platform.

Symptoms

Errors found in messages file:

May 5 14:09:48 SERVER root: ========================= TEST STARTS NOW ================================
May 5 14:14:10 SERVER kernel: [ 3756.855763] device eth0 entered promiscuous mode
May 5 14:18:49 SERVER kernel: [ 4035.419661] nfs: server <IP ADDR> not responding, still trying
May 5 14:18:49 SERVER kernel: [ 4035.419780] nfs: server <IP ADDR> not responding, still trying
..............
May 5 14:18:50 SERVER kernel: [ 4036.831013] nfs: server <IP ADDR> not responding, still trying
May 5 14:19:01 SERVER kernel: [ 4047.126226] nfs: server <IP ADDR> not responding, still trying
May 5 14:19:16 SERVER kernel: [ 4061.935320] nfs: server <IP ADDR> not responding, still trying
May 5 14:21:01 SERVER kernel: [ 4167.198209] nfs: server <IP ADDR> not responding, still trying
May 5 14:21:32 SERVER kernel: [ 4198.769551] INFO: task iozone:8165 blocked for more than 120 seconds.
May 5 14:21:32 SERVER kernel: [ 4198.769735] Not tainted 4.1.12-94.1.8.el6uek.x86_64 #2
May 5 14:21:32 SERVER kernel: [ 4198.769907] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
May 5 14:21:32 SERVER kernel: [ 4198.770191] iozone D ffff88247e957640 0 8165 8161 0x00000080
May 5 14:21:32 SERVER kernel: [ 4198.770497] ffff8806451c3b28 0000000000000082 ffff881226188000 ffff88242a192a00
May 5 14:21:32 SERVER kernel: [ 4198.770990] 006fffff80080008 ffff8806451c0008 ffff88247e957640 7fffffffffffffff
May 5 14:21:32 SERVER kernel: [ 4198.771508] 0000000000000002 ffff8806451c3cc0 ffff8806451c3b48 ffffffff816e0b8e
May 5 14:21:32 SERVER kernel: [ 4198.772026] Call Trace:
May 5 14:21:32 SERVER kernel: [ 4198.772198] [<ffffffff816e0b8e>] schedule+0x3e/0x90
May 5 14:21:32 SERVER kernel: [ 4198.772450] [<ffffffff816e3a75>] schedule_timeout+0x1b5/0x210
May 5 14:21:32 SERVER kernel: [ 4198.772637] [<ffffffff81020af9>] ? read_tsc+0x9/0x10
May 5 14:21:32 SERVER kernel: [ 4198.772852] [<ffffffff810f7bb2>] ? ktime_get+0x52/0xc0
May 5 14:21:32 SERVER kernel: [ 4198.773024] [<ffffffff81020af9>] ? read_tsc+0x9/0x10
May 5 14:21:32 SERVER kernel: [ 4198.773196] [<ffffffff810f7bb2>] ? ktime_get+0x52/0xc0
May 5 14:21:32 SERVER kernel: [ 4198.773368] [<ffffffff816e0184>] io_schedule_timeout+0xa4/0x110
May 5 14:21:32 SERVER kernel: [ 4198.773590] [<ffffffff810cadce>] ? prepare_to_wait+0x5e/0x90
May 5 14:21:32 SERVER kernel: [ 4198.773770] [<ffffffff816e1380>] ? out_of_line_wait_on_bit+0x90/0x90
May 5 14:21:32 SERVER kernel: [ 4198.773986] [<ffffffff816e139f>] bit_wait_io+0x1f/0x60
May 5 14:21:32 SERVER kernel: [ 4198.774158] [<ffffffff816e1208>] __wait_on_bit+0x68/0xa0
May 5 14:21:32 SERVER kernel: [ 4198.774331] [<ffffffff8118c356>] wait_on_page_bit+0xc6/0xd0
May 5 14:21:32 SERVER kernel: [ 4198.774514] [<ffffffff810ca910>] ? wake_atomic_t_function+0x40/0x40
May 5 14:21:32 SERVER kernel: [ 4198.774696] [<ffffffff8118c9e5>] filemap_fdatawait_range+0x105/0x1d0
May 5 14:21:32 SERVER kernel: [ 4198.774909] [<ffffffff8118cbf0>] filemap_write_and_wait_range+0xb0/0xf0
May 5 14:21:32 SERVER kernel: [ 4198.775106] [<ffffffffa0348097>] nfs_file_fsync+0x57/0x170 [nfs]
May 5 14:21:32 SERVER kernel: [ 4198.775287] [<ffffffff8123c60c>] vfs_fsync_range+0x4c/0xc0
May 5 14:21:32 SERVER kernel: [ 4198.775506] [<ffffffff8123c69c>] vfs_fsync+0x1c/0x20
May 5 14:21:32 SERVER kernel: [ 4198.775691] [<ffffffffa0348cfa>] nfs_file_flush+0x8a/0xd0 [nfs]
May 5 14:21:32 SERVER kernel: [ 4198.775902] [<ffffffff8120829a>] filp_close+0x3a/0x90
May 5 14:21:32 SERVER kernel: [ 4198.776078] [<ffffffff81228932>] __close_fd+0x92/0xc0
May 5 14:21:32 SERVER kernel: [ 4198.776249] [<ffffffff81208231>] SyS_close+0x21/0x50
May 5 14:21:32 SERVER kernel: [ 4198.776493] [<ffffffff816e4dae>] system_call_fastpath+0x12/0x71
May 5 14:23:23 SERVER kernel: [ 4309.518656] device eth0 left promiscuous mode
May 5 14:23:32 SERVER kernel: [ 4318.720583] INFO: task iozone:8165 blocked for more than 120 seconds.
May 5 14:23:32 SERVER kernel: [ 4318.720767] Not tainted 4.1.12-94.1.8.el6uek.x86_64 #2
May 5 14:23:32 SERVER kernel: [ 4318.720939] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
May 5 14:23:32 SERVER kernel: [ 4318.721224] iozone D ffff88247e957640 0 8165 8161 0x00000080
May 5 14:23:32 SERVER kernel: [ 4318.721523] ffff8806451c3b28 0000000000000082 ffff881226188000 ffff88242a192a00
May 5 14:23:32 SERVER kernel: [ 4318.724498] 006fffff80080008 ffff8806451c0008 ffff88247e957640 7fffffffffffffff
May 5 14:23:32 SERVER kernel: [ 4318.724971] 0000000000000002 ffff8806451c3cc0 ffff8806451c3b48 ffffffff816e0b8e
May 5 14:23:32 SERVER kernel: [ 4318.725450] Call Trace:

 

Changes

This only occurs with NFS traffic with tcp_timestamps disabled.

 

Cause

To view full details, sign in with your My Oracle Support account.

Don't have a My Oracle Support account? Click to get started!


In this Document
Symptoms
Changes
Cause
Solution
References


My Oracle Support provides customers with access to over a million knowledge articles and a vibrant support community of peers and Oracle experts.