From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: from aserp1040.oracle.com ([141.146.126.69]:27768 "EHLO aserp1040.oracle.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1751914AbdGFAYo (ORCPT ); Wed, 5 Jul 2017 20:24:44 -0400 Date: Wed, 5 Jul 2017 17:24:36 -0700 From: "Darrick J. Wong" Subject: Re: XFS hang - 4.4.73 longterm Message-ID: <20170706002436.GA5068@magnolia> References: <12EF8D94C6F8734FB2FF37B9FBEDD173010E02746C@EXCHANGE.collogia.de> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline In-Reply-To: <12EF8D94C6F8734FB2FF37B9FBEDD173010E02746C@EXCHANGE.collogia.de> Sender: linux-xfs-owner@vger.kernel.org List-ID: List-Id: xfs To: Markus Stockhausen Cc: "'linux-xfs@vger.kernel.org'" On Wed, Jul 05, 2017 at 07:19:28PM +0000, Markus Stockhausen wrote: > Hi, > > we are using a NFS/XFS fileserver and installed the current 4.4.73 longterm kernel. > From time to time (reason currently unidentified) it spits blocked for 120s messages > Like the attached ones. Any ideas what might be the reason? I can reproduce it > With some effort. So in case you want some more logging don't hesitate to ask. > > For more details see https://bugzilla.kernel.org/show_bug.cgi?id=196259 > > [1248134.772889] INFO: task nfsd:1623 blocked for more than 120 seconds. > [1248134.772895] Tainted: G I 4.4.73-2.el7.centos.x86_64 #1 > [1248134.772897] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. > [1248134.772899] nfsd D ffff880bbf08b9c8 0 1623 2 0x00000080 > [1248134.772905] ffff880bbf08b9c8 ffff880be0875400 ffff880bbf080000 ffff880bbf08c000 > [1248134.772908] 0000000000000000 7fffffffffffffff ffff880bbf08bb38 ffffffff816fbb40 > [1248134.772911] ffff880bbf08b9e0 ffffffff816fb2d5 ffff880c176d6d00 ffff880bbf08ba88 > [1248134.772915] Call Trace: > [1248134.772923] [] ? bit_wait+0x50/0x50 > [1248134.772926] [] schedule+0x35/0x80 > [1248134.772929] [] schedule_timeout+0x237/0x2d0 > [1248134.772935] [] ? ip_output+0x6e/0xe0 > [1248134.772938] [] ? __ip_local_out+0x92/0x110 > [1248134.772941] [] ? ktime_get+0x3a/0x90 > [1248134.772944] [] ? bit_wait+0x50/0x50 > [1248134.772947] [] io_schedule_timeout+0xa6/0x110 > [1248134.772950] [] bit_wait_io+0x1b/0x60 > [1248134.772952] [] __wait_on_bit_lock+0x4e/0xb0 > [1248134.772958] [] __lock_page+0xb9/0xe0 Waiting for a page lock with ILOCK held... > [1248134.772962] [] ? autoremove_wake_function+0x40/0x40 > [1248134.773007] [] xfs_find_get_desired_pgoff.isra.10+0x1e0/0x2d0 [xfs] > [1248134.773039] [] xfs_seek_hole_data+0x23d/0x2c0 [xfs] > [1248134.773054] [] ? nfs4_preprocess_stateid_op+0x11c/0x430 [nfsd] > [1248134.773086] [] xfs_file_llseek+0x1c/0x40 [xfs] > [1248134.773090] [] vfs_llseek+0x2e/0x30 > [1248134.773101] [] nfsd4_seek+0x80/0xe0 [nfsd] > [1248134.773112] [] nfsd4_proc_compound+0x3b6/0x710 [nfsd] > [1248134.773121] [] nfsd_dispatch+0xce/0x270 [nfsd] > [1248134.773142] [] svc_process_common+0x454/0x720 [sunrpc] > [1248134.773151] [] ? nfsd_destroy+0x60/0x60 [nfsd] > [1248134.773168] [] svc_process+0x105/0x1c0 [sunrpc] > [1248134.773177] [] nfsd+0xf0/0x160 [nfsd] > [1248134.773180] [] kthread+0xe5/0x100 > [1248134.773183] [] ? kthread_park+0x60/0x60 > [1248134.773187] [] ret_from_fork+0x3f/0x70 > [1248134.773190] [] ? kthread_park+0x60/0x60 > [1248134.773193] INFO: task nfsd:1624 blocked for more than 120 seconds. > [1248134.773195] Tainted: G I 4.4.73-2.el7.centos.x86_64 #1 > [1248134.773197] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. > [1248134.773198] nfsd D ffff880bbf1a7738 0 1624 2 0x00000080 > [1248134.773202] ffff880bbf1a7738 ffffffff81a79500 ffff880bbf081500 ffff880bbf1a8000 > [1248134.773205] ffff8802334477a8 ffff880233447790 ffffffff00000000 ffffffff00000001 > [1248134.773208] ffff880bbf1a7750 ffffffff816fb2d5 ffff880bbf081500 ffff880bbf1a77e0 > [1248134.773211] Call Trace: > [1248134.773214] [] schedule+0x35/0x80 > [1248134.773217] [] rwsem_down_write_failed+0x1f5/0x320 > [1248134.773243] [] ? xfs_bmap_search_extents+0x72/0xe0 [xfs] > [1248134.773273] [] ? __xfs_get_blocks+0x162/0x800 [xfs] > [1248134.773276] [] call_rwsem_down_write_failed+0x13/0x20 > [1248134.773279] [] ? down_write+0x2d/0x40 > [1248134.773311] [] xfs_ilock+0xea/0x130 [xfs] ...and waiting for the ILOCK with page lock held. This is the known deadlock in SEEK_HOLE/SEEK_DATA; I have patches queued to fix it in 4.13, as soon as the dust settles and I send the pull req. --D > [1248134.773341] [] __xfs_get_blocks+0x162/0x800 [xfs] > [1248134.773371] [] xfs_get_blocks+0x14/0x20 [xfs] > [1248134.773375] [] __block_write_begin+0x1a9/0x4c0 > [1248134.773405] [] ? __xfs_get_blocks+0x800/0x800 [xfs] > [1248134.773435] [] xfs_vm_write_begin+0x51/0xe0 [xfs] > [1248134.773464] [] ? xfs_vm_write_end+0x29/0x80 [xfs] > [1248134.773468] [] generic_perform_write+0xcf/0x1c0 > [1248134.773499] [] xfs_file_buffered_aio_write+0x135/0x290 [xfs] > [1248134.773503] [] ? selinux_file_permission+0xc3/0x110 > [1248134.773534] [] xfs_file_write_iter+0x91/0x150 [xfs] > [1248134.773566] [] ? xfs_file_buffered_aio_write+0x290/0x290 [xfs] > [1248134.773569] [] do_readv_writev+0x1ec/0x2a0 > [1248134.773573] [] ? _raw_spin_unlock_irqrestore+0x15/0x20 > [1248134.773576] [] ? __wake_up+0x44/0x50 > [1248134.773579] [] vfs_writev+0x39/0x50 > [1248134.773589] [] nfsd_vfs_write+0xc0/0x370 [nfsd] > [1248134.773600] [] nfsd4_write+0x1a3/0x200 [nfsd] > [1248134.773611] [] nfsd4_proc_compound+0x3b6/0x710 [nfsd] > [1248134.773620] [] nfsd_dispatch+0xce/0x270 [nfsd] > [1248134.773638] [] svc_process_common+0x454/0x720 [sunrpc] > [1248134.773647] [] ? nfsd_destroy+0x60/0x60 [nfsd] > [1248134.773664] [] svc_process+0x105/0x1c0 [sunrpc] > [1248134.773672] [] nfsd+0xf0/0x160 [nfsd] > [1248134.773675] [] kthread+0xe5/0x100 > [1248134.773678] [] ? kthread_park+0x60/0x60 > [1248134.773682] [] ret_from_fork+0x3f/0x70 > [1248134.773684] [] ? kthread_park+0x60/0x60 > > Thanks in advance. > > Markus > **************************************************************************** > Diese E-Mail enth??lt vertrauliche und/oder rechtlich gesch??tzte > Informationen. Wenn Sie nicht der richtige Adressat sind oder diese E-Mail > irrt??mlich erhalten haben, informieren Sie bitte sofort den Absender und > vernichten Sie diese Mail. Das unerlaubte Kopieren sowie die unbefugte > Weitergabe dieser Mail ist nicht gestattet. > > ??ber das Internet versandte E-Mails k??nnen unter fremden Namen erstellt oder > manipuliert werden. Deshalb ist diese als E-Mail verschickte Nachricht keine > rechtsverbindliche Willenserkl??rung. > > Collogia > Unternehmensberatung AG > Ubierring 11 > D-50678 K??ln > > Vorstand: > Kadir Akin > Dr. Michael H??hnerbach > > Vorsitzender des Aufsichtsrates: > Hans Kristian Langva > > Registergericht: Amtsgericht K??ln > Registernummer: HRB 52 497 > > This e-mail may contain confidential and/or privileged information. If you > are not the intended recipient (or have received this e-mail in error) > please notify the sender immediately and destroy this e-mail. Any > unauthorized copying, disclosure or distribution of the material in this > e-mail is strictly forbidden. > > e-mails sent over the internet may have been written under a wrong name or > been manipulated. That is why this message sent as an e-mail is not a > legally binding declaration of intention. > > Collogia > Unternehmensberatung AG > Ubierring 11 > D-50678 K??ln > > executive board: > Kadir Akin > Dr. Michael H??hnerbach > > President of the supervisory board: > Hans Kristian Langva > > Registry office: district court Cologne > Register number: HRB 52 497 > > ****************************************************************************