2021-04-28 17:35:37

by kernel test robot

[permalink] [raw]
Subject: 1e7f963d62: WARNING:at_drivers/scsi/scsi_error.c:#scsi_eh_restore_cmnd



Greeting,

FYI, we noticed the following commit (built with gcc-9):

commit: 1e7f963d622a659baf2a71c369b8b3fe0c5ea39a ("Use a member variable to track the SCSI command submitter")
https://github.com/bvanassche/linux scsi-remove-done-callback


in testcase: xfstests
version: xfstests-x86_64-73c0871-1_20210401
with following parameters:

disk: 4HDD
fs: xfs
test: xfs-group-36
ucode: 0x21

test-description: xfstests is a regression test suite for xfs and other files ystems.
test-url: git://git.kernel.org/pub/scm/fs/xfs/xfstests-dev.git


on test machine: 4 threads Intel(R) Core(TM) i3-3220 CPU @ 3.30GHz with 8G memory

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):



If you fix the issue, kindly add following tag
Reported-by: kernel test robot <[email protected]>


[ 33.728095] WARNING: CPU: 1 PID: 320 at drivers/scsi/scsi_error.c:1046 scsi_eh_restore_cmnd (kbuild/src/consumer/drivers/scsi/scsi_error.c:1046 (discriminator 1))
[ 33.728246] Modules linked in: xfs dm_mod netconsole btrfs blake2b_generic xor zstd_compress raid6_pq libcrc32c sd_mod t10_pi sg intel_rapl_msr intel_rapl_common x86_pkg_temp_thermal i915 intel_powerclamp coretemp intel_gtt crct10dif_pclmul drm_kms_helper ahci crc32_pclmul crc32c_intel syscopyarea ghash_clmulni_intel libahci rapl sysfillrect libata sysimgblt fb_sys_fops intel_cstate mei_me drm usb_storage intel_uncore mei ipmi_devintf ipmi_msghandler video ip_tables
[ 33.728929] CPU: 1 PID: 320 Comm: usb-storage Tainted: G W 5.12.0-rc1-00472-g1e7f963d622a #1
[ 33.729083] Hardware name: Hewlett-Packard p6-1451cx/2ADA, BIOS 8.15 02/05/2013
[ 33.729200] RIP: 0010:scsi_eh_restore_cmnd (kbuild/src/consumer/drivers/scsi/scsi_error.c:1046 (discriminator 1))
[ 33.729284] Code: 06 89 87 70 01 00 00 8b 46 04 89 47 24 8b 46 10 89 87 08 01 00 00 0f b6 46 15 88 87 d0 00 00 00 8b 46 08 89 87 b8 00 00 00 c3 <0f> 0b eb 8a 66 66 2e 0f 1f 84 00 00 00 00 00 66 66 2e 0f 1f 84 00
All code
========
0: 06 (bad)
1: 89 87 70 01 00 00 mov %eax,0x170(%rdi)
7: 8b 46 04 mov 0x4(%rsi),%eax
a: 89 47 24 mov %eax,0x24(%rdi)
d: 8b 46 10 mov 0x10(%rsi),%eax
10: 89 87 08 01 00 00 mov %eax,0x108(%rdi)
16: 0f b6 46 15 movzbl 0x15(%rsi),%eax
1a: 88 87 d0 00 00 00 mov %al,0xd0(%rdi)
20: 8b 46 08 mov 0x8(%rsi),%eax
23: 89 87 b8 00 00 00 mov %eax,0xb8(%rdi)
29: c3 retq
2a:* 0f 0b ud2 <-- trapping instruction
2c: eb 8a jmp 0xffffffffffffffb8
2e: 66 66 2e 0f 1f 84 00 data16 nopw %cs:0x0(%rax,%rax,1)
35: 00 00 00 00
39: 66 data16
3a: 66 data16
3b: 2e cs
3c: 0f .byte 0xf
3d: 1f (bad)
3e: 84 00 test %al,(%rax)

Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: eb 8a jmp 0xffffffffffffff8e
4: 66 66 2e 0f 1f 84 00 data16 nopw %cs:0x0(%rax,%rax,1)
b: 00 00 00 00
f: 66 data16
10: 66 data16
11: 2e cs
12: 0f .byte 0xf
13: 1f (bad)
14: 84 00 test %al,(%rax)
[ 33.729588] RSP: 0018:ffffc9000065be00 EFLAGS: 00010246
[ 33.729675] RAX: 0000000000000000 RBX: ffff88821f139840 RCX: 0000000053425355
[ 33.729790] RDX: 0000000000000000 RSI: ffffc9000065be10 RDI: ffff888219ecd110
[ 33.729903] RBP: 0000000000000001 R08: 000000000000000d R09: ffff88821faabc58
[ 33.730017] R10: 000000000000000f R11: fffffffffff9878e R12: ffff888219ecd110
[ 33.730131] R13: 0000000000000000 R14: 0000000000000012 R15: ffffc90000567a20
[ 33.730246] FS: 0000000000000000(0000) GS:ffff88821fa80000(0000) knlGS:0000000000000000
[ 33.730377] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 33.730482] CR2: 000055b65ec06dd0 CR3: 000000021e00a006 CR4: 00000000001706e0
[ 33.730600] Call Trace:
[ 33.730646] usb_stor_invoke_transport (kbuild/src/consumer/arch/x86/include/asm/bitops.h:207 kbuild/src/consumer/include/asm-generic/bitops/instrumented-non-atomic.h:135 kbuild/src/consumer/drivers/usb/storage/transport.c:728) usb_storage
[ 33.730757] usb_stor_control_thread (kbuild/src/consumer/drivers/usb/storage/usb.c:381) usb_storage
[ 33.730857] ? fill_inquiry_response (kbuild/src/consumer/drivers/usb/storage/usb.c:299) usb_storage
[ 33.730953] ? fill_inquiry_response (kbuild/src/consumer/drivers/usb/storage/usb.c:299) usb_storage
[ 33.731050] kthread (kbuild/src/consumer/kernel/kthread.c:292)
[ 33.731110] ? kthread_park (kbuild/src/consumer/kernel/kthread.c:245)
[ 33.731175] ret_from_fork (kbuild/src/consumer/arch/x86/entry/entry_64.S:300)
[ 33.731241] ---[ end trace 924a042cfbdcc12c ]---
[ 33.859651] 262144 bytes (262 kB, 256 KiB) copied, 0.0245905 s, 10.7 MB/s
[ 33.859656]
[ 33.860165] 512+0 records in
[ 33.860166]
[ 33.860649] 512+0 records out
[ 33.860651]
[ 33.862084] 262144 bytes (262 kB, 256 KiB) copied, 0.120131 s, 2.2 MB/s
[ 33.862086]
[ 33.862596] 512+0 records in
[ 33.862597]
[ 33.863052] 512+0 records out
[ 33.863053]
[ 33.864562] 262144 bytes (262 kB, 256 KiB) copied, 0.0840291 s, 3.1 MB/s
[ 33.864565]
[ 36.323963] XFS (sdb4): Mounting V5 Filesystem
[ 36.380824] XFS (sdb4): Ending clean mount
[ 36.381681] XFS (sdb4): Quotacheck needed: Please wait.
[ 36.394965] XFS (sdb4): Quotacheck: Done.
[ 36.396103] xfs filesystem being mounted at /fs/scratch supports timestamps until 2038 (0x7fffffff)
[ 89.840283] xfs/360 IPMI BMC is not supported on this machine, skip bmc-watchdog setup!
[ 89.840288]
[ 124.291626] XFS (sdb4): Unmounting Filesystem
[ 124.606877] XFS (sdb4): Mounting V5 Filesystem
[ 124.731287] XFS (sdb4): Ending clean mount
[ 124.768740] xfs filesystem being mounted at /fs/scratch supports timestamps until 2038 (0x7fffffff)
[ 124.852863] XFS (sdb4): Unmounting Filesystem
[ 130.967825] XFS (sdb1): Unmounting Filesystem
[ 131.069158] _check_dmesg: something found in dmesg (see /lkp/benchmarks/xfstests/results//xfs/360.dmesg)
[ 131.069164]
[ 131.070749]
[ 131.070753]
[ 131.090913] run fstests xfs/361 at 2021-04-28 04:34:28
[ 131.536011] XFS (sdb1): Mounting V5 Filesystem
[ 131.638167] XFS (sdb1): Ending clean mount
[ 131.701786] xfs filesystem being mounted at /fs/sdb1 supports timestamps until 2038 (0x7fffffff)
[ 132.032132] ------------[ cut here ]------------


To reproduce:

git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp split-job --compatible job.yaml
bin/lkp run compatible-job.yaml



---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation

Thanks,
Oliver Sang


Attachments:
(No filename) (7.12 kB)
config-5.12.0-rc1-00472-g1e7f963d622a (175.51 kB)
job-script (5.75 kB)
dmesg.xz (5.69 kB)
xfstests (1.83 kB)
job.yaml (4.77 kB)
reproduce (937.00 B)
Download all attachments