[LU-3317] ASSERTION( it_disposition(it, 0x00800000) ) failed:

• Type: Bug
• Resolution: Cannot Reproduce
• Priority: Critical
• Environment: https://build.whamcloud.com/job/lustre-master/1481/; ; Run auster test-group/full while setting NRS policies in a loop
• Severity: 3
• Assignee: WC Triage
• Reporter: Minh Diep

Description

Lustre: DEBUG MARKER: ----============= acceptance-small: racer ============---- Fri May 10 00:49:19 PDT 2013
Lustre: DEBUG MARKER: excepting tests:
Lustre: Layout lock feature supported.
Lustre: Mounted smplust-client
Lustre: DEBUG MARKER: Using TIMEOUT=20
Lustre: DEBUG MARKER: == racer test 1: racer on clients: c08,c09,c10,c11,c12,c13,c14,c15,c18,c19,c20,c21,c22,c23,c24 DURATION=900 == 00:49:23 (1368172163)
LustreError: 3853:0:(file.c:2718:ll_inode_revalidate_fini()) smplust: revalidate FID [0x200003ac7:0x359:0x0] error: rc = -116
LustreError: 21202:0:(file.c:2718:ll_inode_revalidate_fini()) smplust: revalidate FID [0x200003ac0:0x912:0x0] error: rc = -116
LustreError: 6574:0:(file.c:2718:ll_inode_revalidate_fini()) smplust: revalidate FID [0x200003ab7:0xfe3:0x0] error: rc = -116
LustreError: 12957:0:(file.c:2718:ll_inode_revalidate_fini()) smplust: revalidate FID [0x200003acf:0xf51:0x0] error: rc = -116
LustreError: 21717:0:(file.c:2718:ll_inode_revalidate_fini()) smplust: revalidate FID [0x200003ac6:0x1580:0x0] error: rc = -116
LustreError: 11096:0:(file.c:2718:ll_inode_revalidate_fini()) smplust: revalidate FID [0x200003ace:0x1cf6:0x0] error: rc = -116
LustreError: 30826:0:(file.c:2718:ll_inode_revalidate_fini()) smplust: revalidate FID [0x200003ad1:0x1f9b:0x0] error: rc = -116
LustreError: 30826:0:(file.c:2718:ll_inode_revalidate_fini()) Skipped 1 previous similar message
LustreError: 7189:0:(file.c:2718:ll_inode_revalidate_fini()) smplust: revalidate FID [0x200003acc:0x31b6:0x0] error: rc = -116
LustreError: 7189:0:(file.c:2718:ll_inode_revalidate_fini()) Skipped 1 previous similar message
LustreError: 1212:0:(file.c:2718:ll_inode_revalidate_fini()) smplust: revalidate FID [0x200003ab9:0x3b78:0x0] error: rc = -116
LustreError: 1212:0:(file.c:2718:ll_inode_revalidate_fini()) Skipped 3 previous similar messages
LustreError: 11370:0:(file.c:2718:ll_inode_revalidate_fini()) smplust: revalidate FID [0x200003ac2:0x4bbb:0x0] error: rc = -116
LustreError: 11370:0:(file.c:2718:ll_inode_revalidate_fini()) Skipped 2 previous similar messages
LustreError: 19417:0:(file.c:640:ll_file_open()) ASSERTION( it_disposition(it, 0x00800000) ) failed:
LustreError: 19417:0:(file.c:640:ll_file_open()) LBUG
Pid: 19417, comm: ls

Call Trace:
[<ffffffffa03fa895>] libcfs_debug_dumpstack+0x55/0x80 [libcfs]
[<ffffffffa03fae97>] lbug_with_loc+0x47/0xb0 [libcfs]
[<ffffffffa0a0e6e3>] ll_file_open+0xc23/0xc30 [lustre]
[<ffffffffa09efd40>] ? ll_dir_open+0x0/0xf0 [lustre]
[<ffffffffa09efe1b>] ll_dir_open+0xdb/0xf0 [lustre]
[<ffffffff8117e1aa>] __dentry_open+0x10a/0x360
[<ffffffff8121cd9f>] ? security_inode_permission+0x1f/0x30
[<ffffffff8117e514>] nameidata_to_filp+0x54/0x70
[<ffffffff81192f50>] do_filp_open+0x710/0xdd0
[<ffffffff810e6dd5>] ? call_rcu_sched+0x15/0x20
[<ffffffff811a1b60>] ? mntput_no_expire+0x30/0x110
[<ffffffff8119f5e2>] ? alloc_fd+0x92/0x160
[<ffffffff8117df59>] do_sys_open+0x69/0x140
[<ffffffff8100c535>] ? math_state_restore+0x45/0x60
[<ffffffff8117e070>] sys_open+0x20/0x30
[<ffffffff8100b072>] system_call_fastpath+0x16/0x1b

LustreError: dumping log to /tmp/lustre-log.1368172779.19417
LustreError: 6511:0:(file.c:2718:ll_inode_revalidate_fini()) smplust: revalidate FID [0x200003acf:0x77e8:0x0] error: rc = -116
LustreError: 6511:0:(file.c:2718:ll_inode_revalidate_fini()) Skipped 6 previous similar messages
INFO: task ls:19417 blocked for more than 120 seconds.

will provide maloo report if/when it's available