[LU-4503] Panic with lu_ref checks enabled

• Type: Bug
• Resolution: Fixed
• Priority: Critical
• Fix Version/s: Lustre 2.7.0
• Affects Version/s: Lustre 2.6.0
• Labels: MB
• Environment: rhel6 last master with lu_ref checks enabled
• Severity: 3
• Assignee: Zhenyu Xu
• Reporter: Alexey Lyashkov

Description

== sanity-benchmark test dbench: dbench == 13:07:36 (1389949656)
debug=0
running as uid/gid/euid/egid 500/500/500/500, groups:
[touch] [/mnt/lustre/d0_runas_test/f13997]
debug=0
running as uid/gid/euid/egid 500/500/500/500, groups:
[bash] [rundbench] [-D] [/mnt/lustre/d0.rhel6-64.shadowland] [6] [-t] [120]
copying /usr/local/share/client.txt to /mnt/lustre/d0.rhel6-64.shadowland/client.txt

Message from syslogd@rhel6-64 at Jan 17 13:07:37 ...
kernel:LustreError: 16666:0:(lu_ref.c:265:lu_ref_del()) ASSERTION( 0 ) failed:

Message from syslogd@rhel6-64 at Jan 17 13:07:37 ...
kernel:LustreError: 16666:0:(lu_ref.c:265:lu_ref_del()) LBUG

Lustre: ctl-lustre-MDT0000: super-sequence allocation rc = 0 [0x0000000200000400-0x0000000240000400):0:mdt
LustreError: 16666:0:(lu_ref.c:105:lu_ref_print()) lu_ref: ffff88013997cee8 2 0 cl_page_alloc:197
LustreError: 16666:0:(lu_ref.c:107:lu_ref_print())      link: cl_io ffff880097136088
LustreError: 16666:0:(lu_ref.c:107:lu_ref_print())      link: transfer ffff88013997cde0
LustreError: 16666:0:(lu_ref.c:105:lu_ref_print()) lu_ref: ffff88008b045698 2 0 cl_page_alloc:197
LustreError: 16666:0:(lu_ref.c:265:lu_ref_del()) ASSERTION( 0 ) failed: 
LustreError: 16666:0:(lu_ref.c:265:lu_ref_del()) LBUG
Pid: 16666, comm: cp

Call Trace:
 [<ffffffffa03ea8c5>] libcfs_debug_dumpstack+0x55/0x80 [libcfs]
 [<ffffffffa03eaec7>] lbug_with_loc+0x47/0xb0 [libcfs]
 [<ffffffffa05a78ed>] lu_ref_del+0x23d/0x240 [obdclass]
 [<ffffffffa0f9e826>] write_commit_callback+0x86/0xb0 [lustre]
 [<ffffffffa09fb26f>] osc_io_commit_async+0xaf/0x3b0 [osc]
 [<ffffffffa0f9e7a0>] ? write_commit_callback+0x0/0xb0 [lustre]
 [<ffffffffa0f9e7a0>] ? write_commit_callback+0x0/0xb0 [lustre]
 [<ffffffffa05a1dd6>] cl_io_commit_async+0x76/0x130 [obdclass]
 [<ffffffffa0a47917>] lov_io_commit_async+0x2d7/0x500 [lov]
 [<ffffffffa0f9e7a0>] ? write_commit_callback+0x0/0xb0 [lustre]
 [<ffffffffa0f9e7a0>] ? write_commit_callback+0x0/0xb0 [lustre]
 [<ffffffffa05a1dd6>] cl_io_commit_async+0x76/0x130 [obdclass]
 [<ffffffffa0fa0417>] vvp_io_write_commit+0x267/0x8c0 [lustre]
 [<ffffffffa059797b>] ? cl_page_get+0x2b/0x100 [obdclass]
 [<ffffffffa0f8ca3c>] ll_write_end+0xbc/0x3e0 [lustre]
 [<ffffffff81129e5a>] generic_file_buffered_write+0x18a/0x300
 [<ffffffff8153481b>] ? _spin_unlock+0x2b/0x40
 [<ffffffff8112bed0>] __generic_file_aio_write+0x260/0x490
 [<ffffffff8112c173>] ? generic_file_aio_write+0x73/0x100
 [<ffffffff8112c18a>] generic_file_aio_write+0x8a/0x100
 [<ffffffffa0fa0b4b>] vvp_io_write_start+0xdb/0x3d0 [lustre]
 [<ffffffffa05a1efa>] cl_io_start+0x6a/0x140 [obdclass]
 [<ffffffffa05a6024>] cl_io_loop+0xb4/0x1b0 [obdclass]
 [<ffffffffa0f42326>] ll_file_io_generic+0x2b6/0x710 [lustre]
 [<ffffffffa05958a9>] ? cl_env_get+0x29/0x350 [obdclass]
 [<ffffffffa0f42ff2>] ll_file_aio_write+0x142/0x2c0 [lustre]
 [<ffffffffa0f432dc>] ll_file_write+0x16c/0x2a0 [lustre]
 [<ffffffff81196568>] vfs_write+0xb8/0x1a0
 [<ffffffff815342e8>] ? lockdep_sys_exit_thunk+0x35/0x67
 [<ffffffff81196e61>] sys_write+0x51/0x90
 [<ffffffff8100b072>] system_call_fastpath+0x16/0x1b