[btrfs] [confidence: ] c2e3930529: WARNING:at_fs/btrfs/block-group.c:#btrfs_put_block_group[btrfs]
by kernel test robot
(please be noted we tested on head of mainline and linux-next, also a fix
commit, still fail:
# extra tests on linus/master
# bad: [455e73a07f6e288b0061dfcf4fcf54fa9fe06458] Merge tag 'clk-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/clk/linux
# extra tests on linux-next/master
# bad: [27c9d5b3c24af29de643533984f1ba3e650c7c78] Add linux-next specific files for 20220113
# extra tests on fix commit
# bad: [651740a502411793327e2f0741104749c4eedcd1] btrfs: check WRITE_ERR when trying to read an extent buffer
)
Greeting,
FYI, we noticed the following commit (built with gcc-9):
commit: c2e39305299f0118298c2201f6d6cc7d3485f29e ("btrfs: clear extent buffer uptodate when we fail to write it")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: xfstests
version: xfstests-x86_64-972d710-1_20220111
with following parameters:
disk: 4HDD
fs: btrfs
test: generic-group-23
ucode: 0xe2
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) i5-6500 CPU @ 3.20GHz with 32G 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 <oliver.sang(a)intel.com>
[ 124.802690][ T8441] ------------[ cut here ]------------
[ 124.810264][ T8441] WARNING: CPU: 0 PID: 8441 at fs/btrfs/block-group.c:127 btrfs_put_block_group+0x15c/0x200 [btrfs]
[ 124.823140][ T8441] Modules linked in: dm_mod btrfs blake2b_generic xor zstd_compress raid6_pq libcrc32c sd_mod t10_pi sg ipmi_devintf ipmi_msghandler intel_rapl_msr intel_rapl_common x86_pkg_temp_thermal intel_powerclamp coretemp mei_wdt wmi_bmof kvm_intel i915 joydev kvm irqbypass crct10dif_pclmul ahci crc32_pclmul crc32c_intel ghash_clmulni_intel rapl intel_cstate intel_gtt ttm libahci drm_kms_helper syscopyarea sysfillrect mei_me sysimgblt intel_uncore libata fb_sys_fops mei intel_pch_thermal drm wmi video acpi_pad intel_pmc_core ip_tables
[ 124.880435][ T8441] CPU: 0 PID: 8441 Comm: umount Tainted: G I 5.15.0-rc7-00150-gc2e39305299f #1
[ 124.892975][ T8441] Hardware name: Dell Inc. OptiPlex 7040/0Y7WYT, BIOS 1.1.1 10/07/2015
[ 124.903439][ T8441] RIP: 0010:btrfs_put_block_group+0x15c/0x200 [btrfs]
[ 124.912516][ T8441] Code: ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 0f 85 8b 00 00 00 48 8b bd 48 02 00 00 e8 af e4 be c0 48 89 ef 5d 41 5c e9 a4 e4 be c0 <0f> 0b e9 2e ff ff ff 0f 0b e9 fd fe ff ff 4c 89 e7 5d be 03 00 00
[ 124.936699][ T8441] RSP: 0018:ffffc9000aedfc88 EFLAGS: 00010206
[ 124.945050][ T8441] RAX: dffffc0000000000 RBX: dffffc0000000000 RCX: ffffffffc0c9bedf
[ 124.955250][ T8441] RDX: 1ffff1102703a006 RSI: 0000000000000004 RDI: ffff8881381d0030
[ 124.965446][ T8441] RBP: ffff8881381d0000 R08: 0000000000000001 R09: ffffed102703a01f
[ 124.975616][ T8441] R10: ffff8881381d00f3 R11: ffffed102703a01e R12: ffff8881381d00f0
[ 124.985809][ T8441] R13: ffff8881381d0000 R14: ffff88885bde2098 R15: ffff8881381d00c8
[ 124.995975][ T8441] FS: 00007fcba0daa080(0000) GS:ffff888724000000(0000) knlGS:0000000000000000
[ 125.007116][ T8441] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 125.015903][ T8441] CR2: 00007fcba11269f0 CR3: 000000086534a001 CR4: 00000000003706f0
[ 125.026085][ T8441] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 125.036265][ T8441] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 125.046347][ T8441] Call Trace:
[ 125.051798][ T8441] btrfs_free_block_groups+0x5eb/0xf00 [btrfs]
[ 125.060184][ T8441] close_ctree+0x562/0x605 [btrfs]
[ 125.067491][ T8441] ? btrfs_start_pre_rw_mount.cold+0x100/0x100 [btrfs]
[ 125.076496][ T8441] ? mutex_unlock+0x40/0x100
[ 125.083171][ T8441] ? __mutex_unlock_slowpath+0x300/0x300
[ 125.091482][ T8441] ? wait_rcu_exp_gp+0x40/0x40
[ 125.098310][ T8441] generic_shutdown_super+0x136/0x340
[ 125.105798][ T8441] kill_anon_super+0x36/0x80
[ 125.112489][ T8441] btrfs_kill_super+0x38/0x80 [btrfs]
[ 125.119964][ T8441] deactivate_locked_super+0x85/0x100
[ 125.127353][ T8441] cleanup_mnt+0x2bc/0x480
[ 125.133802][ T8441] task_work_run+0xdb/0x180
[ 125.140273][ T8441] exit_to_user_mode_prepare+0x205/0x240
[ 125.147885][ T8441] syscall_exit_to_user_mode+0x12/0x40
[ 125.155261][ T8441] do_syscall_64+0x48/0xc0
[ 125.161626][ T8441] entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 125.169391][ T8441] RIP: 0033:0x7fcba11d0507
[ 125.175660][ T8441] Code: 19 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 66 0f 1f 44 00 00 31 f6 e9 09 00 00 00 66 0f 1f 84 00 00 00 00 00 b8 a6 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 59 19 0c 00 f7 d8 64 89 01 48
[ 125.199102][ T8441] RSP: 002b:00007ffc7df36bb8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a6
[ 125.209405][ T8441] RAX: 0000000000000000 RBX: 00005617a5168970 RCX: 00007fcba11d0507
[ 125.219282][ T8441] RDX: 0000000000000001 RSI: 0000000000000000 RDI: 00005617a5168b80
[ 125.229142][ T8441] RBP: 0000000000000000 R08: 00005617a5168ba0 R09: 00007fcba1251e80
[ 125.238986][ T8441] R10: 0000000000000000 R11: 0000000000000246 R12: 00005617a5168b80
[ 125.248846][ T8441] R13: 00007fcba12f61c4 R14: 00005617a5168a68 R15: 0000000000000000
[ 125.258673][ T8441] ---[ end trace 472f488092ad402d ]---
[ 125.266186][ T8441] ------------[ cut here ]------------
[ 125.273583][ T8441] WARNING: CPU: 1 PID: 8441 at fs/btrfs/block-group.c:3981 btrfs_free_block_groups+0x7e6/0xf00 [btrfs]
[ 125.286545][ T8441] Modules linked in: dm_mod btrfs blake2b_generic xor zstd_compress raid6_pq libcrc32c sd_mod t10_pi sg ipmi_devintf ipmi_msghandler intel_rapl_msr intel_rapl_common x86_pkg_temp_thermal intel_powerclamp coretemp mei_wdt wmi_bmof kvm_intel i915 joydev kvm irqbypass crct10dif_pclmul ahci crc32_pclmul crc32c_intel ghash_clmulni_intel rapl intel_cstate intel_gtt ttm libahci drm_kms_helper syscopyarea sysfillrect mei_me sysimgblt intel_uncore libata fb_sys_fops mei intel_pch_thermal drm wmi video acpi_pad intel_pmc_core ip_tables
[ 125.343092][ T8441] CPU: 1 PID: 8441 Comm: umount Tainted: G W I 5.15.0-rc7-00150-gc2e39305299f #1
[ 125.355381][ T8441] Hardware name: Dell Inc. OptiPlex 7040/0Y7WYT, BIOS 1.1.1 10/07/2015
[ 125.365758][ T8441] RIP: 0010:btrfs_free_block_groups+0x7e6/0xf00 [btrfs]
[ 125.374877][ T8441] Code: 00 4d 89 ec 48 8b 85 98 06 00 00 49 be 00 00 00 00 00 fc ff df 49 c1 ec 03 4d 01 f4 49 39 c5 0f 85 03 01 00 00 e9 7c 01 00 00 <0f> 0b 31 c9 31 d2 4c 89 fe 48 89 ef e8 c9 88 fe ff 48 8d 7b 40 48
[ 125.399168][ T8441] RSP: 0018:ffffc9000aedfca0 EFLAGS: 00010206
[ 125.407369][ T8441] RAX: 1ffff11021576304 RBX: ffff88810abb1870 RCX: ffff88810abb3800
[ 125.417462][ T8441] RDX: 0000000000000028 RSI: ffff8881040b30b8 RDI: ffff88810abb1820
[ 125.427555][ T8441] RBP: ffff88885bde2000 R08: 000000008d12fd33 R09: ffffed10e4816115
[ 125.437604][ T8441] R10: ffff8887240b08a3 R11: ffffed10e4816114 R12: ffffed110b7bc4d3
[ 125.447651][ T8441] R13: ffff88885bde2698 R14: dffffc0000000000 R15: ffff88810abb1800
[ 125.457665][ T8441] FS: 00007fcba0daa080(0000) GS:ffff888724080000(0000) knlGS:0000000000000000
[ 125.468604][ T8441] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 125.477195][ T8441] CR2: 00007f99d9138000 CR3: 000000086534a001 CR4: 00000000003706e0
[ 125.487079][ T8441] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 125.496919][ T8441] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 125.506826][ T8441] Call Trace:
[ 125.512016][ T8441] close_ctree+0x562/0x605 [btrfs]
[ 125.519043][ T8441] ? btrfs_start_pre_rw_mount.cold+0x100/0x100 [btrfs]
[ 125.527798][ T8441] ? mutex_unlock+0x40/0x100
[ 125.534206][ T8441] ? __mutex_unlock_slowpath+0x300/0x300
[ 125.542235][ T8441] ? wait_rcu_exp_gp+0x40/0x40
[ 125.548839][ T8441] generic_shutdown_super+0x136/0x340
[ 125.556013][ T8441] kill_anon_super+0x36/0x80
[ 125.562410][ T8441] btrfs_kill_super+0x38/0x80 [btrfs]
[ 125.569634][ T8441] deactivate_locked_super+0x85/0x100
[ 125.576696][ T8441] cleanup_mnt+0x2bc/0x480
[ 125.582867][ T8441] task_work_run+0xdb/0x180
[ 125.589080][ T8441] exit_to_user_mode_prepare+0x205/0x240
[ 125.596418][ T8441] syscall_exit_to_user_mode+0x12/0x40
[ 125.603645][ T8441] do_syscall_64+0x48/0xc0
[ 125.609754][ T8441] entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 125.617353][ T8441] RIP: 0033:0x7fcba11d0507
[ 125.623493][ T8441] Code: 19 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 66 0f 1f 44 00 00 31 f6 e9 09 00 00 00 66 0f 1f 84 00 00 00 00 00 b8 a6 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 59 19 0c 00 f7 d8 64 89 01 48
[ 125.646695][ T8441] RSP: 002b:00007ffc7df36bb8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a6
[ 125.656891][ T8441] RAX: 0000000000000000 RBX: 00005617a5168970 RCX: 00007fcba11d0507
[ 125.666668][ T8441] RDX: 0000000000000001 RSI: 0000000000000000 RDI: 00005617a5168b80
[ 125.676483][ T8441] RBP: 0000000000000000 R08: 00005617a5168ba0 R09: 00007fcba1251e80
[ 125.686246][ T8441] R10: 0000000000000000 R11: 0000000000000246 R12: 00005617a5168b80
[ 125.696059][ T8441] R13: 00007fcba12f61c4 R14: 00005617a5168a68 R15: 0000000000000000
[ 125.705877][ T8441] ---[ end trace 472f488092ad402e ]---
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
sudo bin/lkp install job.yaml # job file is attached in this email
bin/lkp split-job --compatible job.yaml # generate the yaml file for lkp run
sudo bin/lkp run generated-yaml-file
# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.
---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation
Thanks,
Oliver Sang
6 months, 4 weeks
- 3
- 2
[loop] 322c4293ec: xfstests.xfs.014.fail
by kernel test robot
(please be noted we reported "[loop] 322c4293ec: xfstests.xfs.049.fail"
on https://lists.01.org/hyperkitty/list/[email protected]/thread/FQDXNHC7CYZQ...
when this commit still on linux-next, and we noticed some discussion about
the fix there. since now the commit merged to mainline and issues still
exists, we reported this again as a reminder. Thanks)
Greeting,
FYI, we noticed the following commit (built with gcc-9):
commit: 322c4293ecc58110227b49d7e47ae37b9b03566f ("loop: make autoclear operation asynchronous")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: xfstests
version: xfstests-x86_64-972d710-1_20211221
with following parameters:
disk: 4HDD
fs: xfs
test: xfs-group-01
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 1 sockets 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 <oliver.sang(a)intel.com>
2022-01-13 03:59:39 export TEST_DIR=/fs/sda2
2022-01-13 03:59:39 export TEST_DEV=/dev/sda2
2022-01-13 03:59:39 export FSTYP=xfs
2022-01-13 03:59:39 export SCRATCH_MNT=/fs/scratch
2022-01-13 03:59:39 mkdir /fs/scratch -p
2022-01-13 03:59:39 export SCRATCH_DEV=/dev/sda5
2022-01-13 03:59:39 export SCRATCH_LOGDEV=/dev/sda3
2022-01-13 03:59:39 export SCRATCH_XFS_LIST_METADATA_FIELDS=u3.sfdir3.hdr.parent.i4
2022-01-13 03:59:39 export SCRATCH_XFS_LIST_FUZZ_VERBS=random
2022-01-13 03:59:39 sed "s:^:xfs/:" //lkp/benchmarks/xfstests/tests/xfs-group-01
2022-01-13 03:59:39 ./check xfs/010 xfs/011 xfs/012 xfs/013 xfs/014 xfs/015 xfs/016 xfs/017 xfs/018 xfs/019
FSTYP -- xfs (debug)
PLATFORM -- Linux/x86_64 lkp-ivb-d04 5.16.0-rc3-00080-g322c4293ecc5 #1 SMP Thu Jan 13 10:02:46 CST 2022
MKFS_OPTIONS -- -f /dev/sda5
MOUNT_OPTIONS -- /dev/sda5 /fs/scratch
xfs/010 9s
xfs/011 21s
xfs/012 2s
xfs/013 344s
xfs/014 - output mismatch (see /lkp/benchmarks/xfstests/results//xfs/014.out.bad)
--- tests/xfs/014.out 2022-01-11 11:03:35.000000000 +0000
+++ /lkp/benchmarks/xfstests/results//xfs/014.out.bad 2022-01-13 04:06:16.565193796 +0000
@@ -1,2 +1,3 @@
QA output created by 014
Silence is golden.
+umount: /fs/scratch: target is busy.
...
(Run 'diff -u /lkp/benchmarks/xfstests/tests/xfs/014.out /lkp/benchmarks/xfstests/results//xfs/014.out.bad' to see the entire diff)
xfs/015 20s
xfs/016 [not run] Cannot run this test using log MKFS_OPTIONS specified
xfs/017 20s
xfs/018 - output mismatch (see /lkp/benchmarks/xfstests/results//xfs/018.out.bad)
--- tests/xfs/018.out 2022-01-11 11:03:35.000000000 +0000
+++ /lkp/benchmarks/xfstests/results//xfs/018.out.bad 2022-01-13 04:07:05.256195969 +0000
@@ -1,17 +1,9 @@
QA output created by 018
*** init FS
-*** compare logprint: 018.op with 018.fulldir/op.mnt-onoalign,logbsize=32k.mkfs-lsize=2000b-llazy-count=1-lversion=1.filtered
-*** compare logprint: 018.trans_inode with 018.fulldir/trans_inode.mnt-onoalign,logbsize=32k.mkfs-lsize=2000b-llazy-count=1-lversion=1.filtered
-*** compare logprint: 018.trans_buf with 018.fulldir/trans_buf.mnt-onoalign,logbsize=32k.mkfs-lsize=2000b-llazy-count=1-lversion=1.filtered
-*** compare logprint: 018.op with 018.fulldir/op.mnt-onoalign,logbsize=32k.mkfs-lsize=2000b-llazy-count=1-lversion=2.filtered
-*** compare logprint: 018.trans_inode with 018.fulldir/trans_inode.mnt-onoalign,logbsize=32k.mkfs-lsize=2000b-llazy-count=1-lversion=2.filtered
...
(Run 'diff -u /lkp/benchmarks/xfstests/tests/xfs/018.out /lkp/benchmarks/xfstests/results//xfs/018.out.bad' to see the entire diff)
xfs/019 3s
Ran: xfs/010 xfs/011 xfs/012 xfs/013 xfs/014 xfs/015 xfs/016 xfs/017 xfs/018 xfs/019
Not run: xfs/016
Failures: xfs/014 xfs/018
Failed 2 of 10 tests
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
sudo bin/lkp install job.yaml # job file is attached in this email
bin/lkp split-job --compatible job.yaml # generate the yaml file for lkp run
sudo bin/lkp run generated-yaml-file
# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.
---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation
Thanks,
Oliver Sang
6 months, 4 weeks
- 1
- 0
[headers/deps] 7ed1f1761b: kernel_BUG_at_mm/usercopy.c
by kernel test robot
Greeting,
FYI, we noticed the following commit (built with clang-14):
commit: 7ed1f1761b34da303e46ad3722a96e7f581686ef ("headers/deps: x86/fpu: Make task_struct::thread constant size")
git://git.kernel.org/cgit/linux/kernel/git/mingo/tip.git master
in testcase: boot
on test machine: qemu-system-i386 -enable-kvm -cpu SandyBridge -smp 2 -m 4G
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 <oliver.sang(a)intel.com>
[ 15.227582][ T170] kernel BUG at mm/usercopy.c:99!
[ 15.228110][ T170] invalid opcode: 0000 [#1]
[ 15.228586][ T170] CPU: 0 PID: 170 Comm: sh Not tainted 5.16.0-rc8-00086-g7ed1f1761b34 #1 f3e03ed0477ca13397711d0e211293db21a3eba3
[ 15.229717][ T170] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[ 15.230568][ T170] EIP: usercopy_abort (mm/usercopy.c:99)
[ 15.231054][ T170] Code: 44 d0 b8 87 81 a6 c3 bb 82 da b9 c3 0f 44 c3 ff 75 0c ff 75 08 50 52 51 57 56 ff 75 f0 68 af db b4 c3 e8 d1 cd 0b 01 83 c4 24 <0f> 0b 68 a8 71 08 c4 e8 dc 7e 49 00 3e 8d 74 26 00 55 89 e5 53 57
All code
========
0: 44 d0 b8 87 81 a6 c3 rex.R sarb -0x3c597e79(%rax)
7: bb 82 da b9 c3 mov $0xc3b9da82,%ebx
c: 0f 44 c3 cmove %ebx,%eax
f: ff 75 0c pushq 0xc(%rbp)
12: ff 75 08 pushq 0x8(%rbp)
15: 50 push %rax
16: 52 push %rdx
17: 51 push %rcx
18: 57 push %rdi
19: 56 push %rsi
1a: ff 75 f0 pushq -0x10(%rbp)
1d: 68 af db b4 c3 pushq $0xffffffffc3b4dbaf
22: e8 d1 cd 0b 01 callq 0x10bcdf8
27: 83 c4 24 add $0x24,%esp
2a:* 0f 0b ud2 <-- trapping instruction
2c: 68 a8 71 08 c4 pushq $0xffffffffc40871a8
31: e8 dc 7e 49 00 callq 0x497f12
36: 3e 8d 74 26 00 lea %ds:0x0(%rsi,%riz,1),%esi
3b: 55 push %rbp
3c: 89 e5 mov %esp,%ebp
3e: 53 push %rbx
3f: 57 push %rdi
Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: 68 a8 71 08 c4 pushq $0xffffffffc40871a8
7: e8 dc 7e 49 00 callq 0x497ee8
c: 3e 8d 74 26 00 lea %ds:0x0(%rsi,%riz,1),%esi
11: 55 push %rbp
12: 89 e5 mov %esp,%ebp
14: 53 push %rbx
15: 57 push %rdi
[ 15.232782][ T170] EAX: 00000069 EBX: c3b9da82 ECX: 00000000 EDX: 00000000
[ 15.233418][ T170] ESI: c3a285c4 EDI: c3bb52c5 EBP: c8af7ce0 ESP: c8af7cd0
[ 15.234094][ T170] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 0068 EFLAGS: 00010292
[ 15.234847][ T170] CR0: 80050033 CR2: 08075077 CR3: 08d24000 CR4: 000406d0
[ 15.235507][ T170] Call Trace:
[ 15.235853][ T170] __check_heap_object (mm/slub.c:4524)
[ 15.236350][ T170] __check_object_size (mm/usercopy.c:240 mm/usercopy.c:286)
[ 15.236843][ T170] copy_from_buffer (include/linux/thread_info.h:? include/linux/thread_info.h:218 include/linux/uaccess.h:191 arch/x86/kernel/fpu/xstate.c:1261)
[ 15.237317][ T170] copy_uabi_to_xstate (arch/x86/kernel/fpu/xstate.c:1313)
[ 15.238693][ T170] ? __local_bh_enable_ip (arch/x86/include/asm/irqflags.h:45 arch/x86/include/asm/irqflags.h:80 kernel/softirq.c:389)
[ 15.239235][ T170] ? trace_x86_fpu_regs_activated (core.c:?)
[ 15.239778][ T170] copy_sigframe_from_user_to_xstate (arch/x86/kernel/fpu/xstate.c:1349)
[ 15.240340][ T170] __fpu_restore_sig (arch/x86/kernel/fpu/signal.c:399)
[ 15.240836][ T170] fpu__restore_sig (arch/x86/kernel/fpu/signal.c:496)
[ 15.241319][ T170] restore_sigcontext (arch/x86/kernel/signal.c:143)
[ 15.241833][ T170] __ia32_sys_sigreturn (arch/x86/kernel/signal.c:?)
[ 15.242345][ T170] ? syscall_enter_from_user_mode (arch/x86/include/asm/irqflags.h:45 arch/x86/include/asm/irqflags.h:80 kernel/entry/common.c:107)
[ 15.242951][ T170] do_int80_syscall_32 (arch/x86/entry/common.c:112 arch/x86/entry/common.c:132)
[ 15.243428][ T170] ? irqentry_exit (kernel/entry/common.c:?)
[ 15.243897][ T170] ? exc_page_fault (arch/x86/mm/fault.c:1545)
[ 15.244361][ T170] entry_INT80_32 (init_task.c:?)
[ 15.244813][ T170] EIP: 0xb7f0807f
[ 15.245191][ T170] Code: 24 04 53 56 57 55 8b 01 85 c0 75 23 8b 44 24 18 8b 5c 24 1c 8b 4c 24 20 8b 54 24 24 8b 74 24 28 8b 7c 24 2c 8b 6c 24 30 cd 80 <5d> 5f 5e 5b c3 5d 5f 5e 5b e9 30 09 00 00 65 8b 15 04 00 00 00 8b
All code
========
0: 24 04 and $0x4,%al
2: 53 push %rbx
3: 56 push %rsi
4: 57 push %rdi
5: 55 push %rbp
6: 8b 01 mov (%rcx),%eax
8: 85 c0 test %eax,%eax
a: 75 23 jne 0x2f
c: 8b 44 24 18 mov 0x18(%rsp),%eax
10: 8b 5c 24 1c mov 0x1c(%rsp),%ebx
14: 8b 4c 24 20 mov 0x20(%rsp),%ecx
18: 8b 54 24 24 mov 0x24(%rsp),%edx
1c: 8b 74 24 28 mov 0x28(%rsp),%esi
20: 8b 7c 24 2c mov 0x2c(%rsp),%edi
24: 8b 6c 24 30 mov 0x30(%rsp),%ebp
28: cd 80 int $0x80
2a:* 5d pop %rbp <-- trapping instruction
2b: 5f pop %rdi
2c: 5e pop %rsi
2d: 5b pop %rbx
2e: c3 retq
2f: 5d pop %rbp
30: 5f pop %rdi
31: 5e pop %rsi
32: 5b pop %rbx
33: e9 30 09 00 00 jmpq 0x968
38: 65 8b 15 04 00 00 00 mov %gs:0x4(%rip),%edx # 0x43
3f: 8b .byte 0x8b
Code starting with the faulting instruction
===========================================
0: 5d pop %rbp
1: 5f pop %rdi
2: 5e pop %rsi
3: 5b pop %rbx
4: c3 retq
5: 5d pop %rbp
6: 5f pop %rdi
7: 5e pop %rsi
8: 5b pop %rbx
9: e9 30 09 00 00 jmpq 0x93e
e: 65 8b 15 04 00 00 00 mov %gs:0x4(%rip),%edx # 0x19
15: 8b .byte 0x8b
[ 15.246931][ T170] EAX: 000000ab EBX: ffffffff ECX: bf923d54 EDX: 00000000
[ 15.247580][ T170] ESI: 00000000 EDI: 00000000 EBP: 00000000 ESP: bf923c9c
[ 15.248244][ T170] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b EFLAGS: 00000246
[ 15.248993][ T170] Modules linked in:
[ 15.249455][ T170] ---[ end trace 03b5c1c68708db3f ]---
[ 15.250512][ T170] EIP: usercopy_abort (mm/usercopy.c:99)
[ 15.250995][ T170] Code: 44 d0 b8 87 81 a6 c3 bb 82 da b9 c3 0f 44 c3 ff 75 0c ff 75 08 50 52 51 57 56 ff 75 f0 68 af db b4 c3 e8 d1 cd 0b 01 83 c4 24 <0f> 0b 68 a8 71 08 c4 e8 dc 7e 49 00 3e 8d 74 26 00 55 89 e5 53 57
All code
========
0: 44 d0 b8 87 81 a6 c3 rex.R sarb -0x3c597e79(%rax)
7: bb 82 da b9 c3 mov $0xc3b9da82,%ebx
c: 0f 44 c3 cmove %ebx,%eax
f: ff 75 0c pushq 0xc(%rbp)
12: ff 75 08 pushq 0x8(%rbp)
15: 50 push %rax
16: 52 push %rdx
17: 51 push %rcx
18: 57 push %rdi
19: 56 push %rsi
1a: ff 75 f0 pushq -0x10(%rbp)
1d: 68 af db b4 c3 pushq $0xffffffffc3b4dbaf
22: e8 d1 cd 0b 01 callq 0x10bcdf8
27: 83 c4 24 add $0x24,%esp
2a:* 0f 0b ud2 <-- trapping instruction
2c: 68 a8 71 08 c4 pushq $0xffffffffc40871a8
31: e8 dc 7e 49 00 callq 0x497f12
36: 3e 8d 74 26 00 lea %ds:0x0(%rsi,%riz,1),%esi
3b: 55 push %rbp
3c: 89 e5 mov %esp,%ebp
3e: 53 push %rbx
3f: 57 push %rdi
Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: 68 a8 71 08 c4 pushq $0xffffffffc40871a8
7: e8 dc 7e 49 00 callq 0x497ee8
c: 3e 8d 74 26 00 lea %ds:0x0(%rsi,%riz,1),%esi
11: 55 push %rbp
12: 89 e5 mov %esp,%ebp
14: 53 push %rbx
15: 57 push %rdi
To reproduce:
# build kernel
cd linux
cp config-5.16.0-rc8-00086-g7ed1f1761b34 .config
make HOSTCC=clang-14 CC=clang-14 ARCH=i386 olddefconfig prepare modules_prepare bzImage modules
make HOSTCC=clang-14 CC=clang-14 ARCH=i386 INSTALL_MOD_PATH=<mod-install-dir> modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.
---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation
Thanks,
Oliver Sang
6 months, 4 weeks
- 1
- 0
[kallsyms/objtool] 6be8b14abc: WARNING:at_kernel/kallsyms.c:#kallsyms_objtool_init
by kernel test robot
Greeting,
FYI, we noticed the following commit (built with clang-14):
commit: 6be8b14abc0b745337681961e3f1bdab6fd016b6 ("kallsyms/objtool: Port kallsyms_relative_base functionality to the kallsyms_syms[] offsets")
git://git.kernel.org/cgit/linux/kernel/git/mingo/tip.git master
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------+------------+------------+
| | 3e44479e22 | 6be8b14abc |
+-----------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 16 | 9 |
| WARNING:at_kernel/kallsyms.c:#.brk.early_pgt_alloc | 16 | |
| RIP:.brk.early_pgt_alloc | 16 | |
| WARNING:at_kernel/kallsyms.c:#kallsyms_objtool_init | 0 | 9 |
| RIP:kallsyms_objtool_init | 0 | 9 |
| WARNING:at_kernel/kallsyms.c:#kallsyms_init | 0 | 9 |
| RIP:kallsyms_init | 0 | 9 |
+-----------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <oliver.sang(a)intel.com>
[ 1.900276][ T0] WARNING: CPU: 0 PID: 0 at kernel/kallsyms.c:1058 kallsyms_objtool_init (kernel/kallsyms.c:1058)
[ 1.902387][ T0] Modules linked in:
[ 1.903014][ T0] CPU: 0 PID: 0 Comm: swapper Not tainted 5.16.0-rc8-00597-g6be8b14abc0b #1
[ 1.904466][ T0] RIP: 0010:kallsyms_objtool_init (kernel/kallsyms.c:1058)
[ 1.905472][ T0] Code: 70 00 b5 ff 31 48 f7 e1 48 89 d1 48 c1 e9 0b 48 c7 c7 40 99 c7 8c 48 89 da e8 0a 6f 31 fd 48 c7 c7 c0 99 c7 8c e8 fe 14 e7 f9 <0f> 0b 48 83 c4 20 5b 41 5c 41 5d 41 5e 41 5f 5d c3 e8 28 3d 04 fa
All code
========
0: 70 00 jo 0x2
2: b5 ff mov $0xff,%ch
4: 31 48 f7 xor %ecx,-0x9(%rax)
7: e1 48 loope 0x51
9: 89 d1 mov %edx,%ecx
b: 48 c1 e9 0b shr $0xb,%rcx
f: 48 c7 c7 40 99 c7 8c mov $0xffffffff8cc79940,%rdi
16: 48 89 da mov %rbx,%rdx
19: e8 0a 6f 31 fd callq 0xfffffffffd316f28
1e: 48 c7 c7 c0 99 c7 8c mov $0xffffffff8cc799c0,%rdi
25: e8 fe 14 e7 f9 callq 0xfffffffff9e71528
2a:* 0f 0b ud2 <-- trapping instruction
2c: 48 83 c4 20 add $0x20,%rsp
30: 5b pop %rbx
31: 41 5c pop %r12
33: 41 5d pop %r13
35: 41 5e pop %r14
37: 41 5f pop %r15
39: 5d pop %rbp
3a: c3 retq
3b: e8 28 3d 04 fa callq 0xfffffffffa043d68
Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: 48 83 c4 20 add $0x20,%rsp
6: 5b pop %rbx
7: 41 5c pop %r12
9: 41 5d pop %r13
b: 41 5e pop %r14
d: 41 5f pop %r15
f: 5d pop %rbp
10: c3 retq
11: e8 28 3d 04 fa callq 0xfffffffffa043d3e
[ 1.908826][ T0] RSP: 0000:ffffffff8e207e98 EFLAGS: 00010046 ORIG_RAX: 0000000000000000
[ 1.910276][ T0] RAX: 0000000000000000 RBX: 0000000000000004 RCX: ffffffff8e2bbac0
[ 1.911613][ T0] RDX: 0000000000000000 RSI: 00000000fffffffe RDI: 0000000000000005
[ 1.912918][ T0] RBP: ffffffff8e207ee0 R08: ffffffff8e2bbac0 R09: fffffbfff1c40f84
[ 1.914233][ T0] R10: 0000000000000000 R11: dffff7fff1c40f85 R12: 000000000003bac3
[ 1.915547][ T0] R13: ffff8883aeffffe0 R14: 000000000003b9ba R15: 0000000000000109
[ 1.916851][ T0] FS: 0000000000000000(0000) GS:ffffffff8e2f7000(0000) knlGS:0000000000000000
[ 1.918326][ T0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1.919414][ T0] CR2: ffff88843ffff000 CR3: 000000025788e000 CR4: 00000000000406b0
[ 1.920689][ T0] Call Trace:
[ 1.921220][ T0] <TASK>
[ 1.921707][ T0] start_kernel (init/main.c:1038)
[ 1.922475][ T0] x86_64_start_reservations (arch/x86/kernel/head64.c:531)
[ 1.923377][ T0] x86_64_start_kernel (arch/x86/kernel/head64.c:512)
[ 1.924217][ T0] secondary_startup_64_no_verify (??:?)
[ 1.925257][ T0] </TASK>
[ 1.925760][ T0] irq event stamp: 0
[ 1.926379][ T0] hardirqs last enabled at (0): 0x0
[ 1.927527][ T0] hardirqs last disabled at (0): 0x0
[ 1.928676][ T0] softirqs last enabled at (0): 0x0
[ 1.929819][ T0] softirqs last disabled at (0): 0x0
[ 1.931023][ T0] random: get_random_bytes called from print_oops_end_marker+0x36/0x80 with crng_init=0
[ 1.931069][ T0] ---[ end trace 0000000000000000 ]---
[ 1.933507][ T0] Kernel command line: ip=::::vm-snb-136::dhcp root=/dev/ram0 RESULT_ROOT=/result/boot/1/vm-snb/yocto-x86_64-minimal-20190520.cgz/x86_64-randconfig-a015-20220109/clang-14/6be8b14abc0b745337681961e3f1bdab6fd016b6/3 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-a015-20220109/clang-14/6be8b14abc0b745337681961e3f1bdab6fd016b6/vmlinuz-5.16.0-rc8-00597-g6be8b14abc0b branch=mingo-tip/sched/headers job=/lkp/jobs/scheduled/vm-snb-136/boot-1-yocto-x86_64-minimal-20190520.cgz-6be8b14abc0b745337681961e3f1bdab6fd016b6-20220113-41318-1uw7et-3.yaml user=lkp ARCH=x86_64 kconfig=x86_64-randconfig-a015-20220109 commit=6be8b14abc0b745337681961e3f1bdab6fd016b6 vmalloc=128M initramfs_async=0 page_owner=on max_uptime=600 LKP_SERVER=internal-lkp-server selinux=0 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level
[ 1.934914][ T0] sysrq: sysrq always enabled.
[ 1.949671][ T0] ignoring the deprecated load_ramdisk= option
[ 1.951570][ T0] Unknown kernel command line parameters "RESULT_ROOT=/result/boot/1/vm-snb/yocto-x86_64-minimal-20190520.cgz/x86_64-randconfig-a015-20220109/clang-14/6be8b14abc0b745337681961e3f1bdab6fd016b6/3 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-a015-20220109/clang-14/6be8b14abc0b745337681961e3f1bdab6fd016b6/vmlinuz-5.16.0-rc8-00597-g6be8b14abc0b branch=mingo-tip/sched/headers job=/lkp/jobs/scheduled/vm-snb-136/boot-1-yocto-x86_64-minimal-20190520.cgz-6be8b14abc0b745337681961e3f1bdab6fd016b6-20220113-41318-1uw7et-3.yaml user=lkp ARCH=x86_64 kconfig=x86_64-randconfig-a015-20220109 commit=6be8b14abc0b745337681961e3f1bdab6fd016b6 vmalloc=128M max_uptime=600 LKP_SERVER=internal-lkp-server selinux=0 softlockup_panic=1 prompt_ramdisk=0 vga=normal", will be passed to user space.
[ 1.968657][ T0] Dentry cache hash table entries: 2097152 (order: 12, 16777216 bytes, linear)
[ 1.980758][ T0] Inode-cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[ 1.982419][ T0] mem auto-init: stack:all(pattern), heap alloc:off, heap free:on
[ 1.983727][ T0] mem auto-init: clearing system memory may take some time...
[ 76.609783][ T0] Memory: 13923808K/16776696K available (59403K kernel code, 16683K rwdata, 21008K rodata, 1820K init, 18208K bss, 2852632K reserved, 0K cma-reserved)
[ 76.613132][ T0] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 76.616820][ T0] ODEBUG: selftest passed
[ 76.617682][ T0] Kernel/User page tables isolation: enabled
[ 76.619370][ T0] per_task: Using 2104 per_task bytes, 8192 bytes available
[ 76.621382][ T0] Running RCU self tests
[ 76.638196][ T0] NR_IRQS: 4352, nr_irqs: 256, preallocated irqs: 16
[ 76.640782][ T0] printk: console [ttyS0] enabled
[ 76.642565][ T0] printk: bootconsole [earlyser0] disabled
[ 76.644620][ T0] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 76.646196][ T0] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 76.647122][ T0] ... MAX_LOCK_DEPTH: 48
[ 76.648018][ T0] ... MAX_LOCKDEP_KEYS: 8192
[ 76.648969][ T0] ... CLASSHASH_SIZE: 4096
[ 76.649911][ T0] ... MAX_LOCKDEP_ENTRIES: 32768
[ 76.650855][ T0] ... MAX_LOCKDEP_CHAINS: 65536
[ 76.651830][ T0] ... CHAINHASH_SIZE: 32768
[ 76.652847][ T0] memory used by lock dependency info: 6877 kB
[ 76.654005][ T0] memory used for stack traces: 4224 kB
[ 76.655076][ T0] per task-struct memory footprint: 2688 bytes
[ 76.656308][ T0] ACPI: Core revision 20210930
[ 76.658111][ T0] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[ 76.660278][ T0] APIC: Switch to symmetric I/O mode setup
[ 76.663523][ T0] masked ExtINT on CPU#0
[ 76.665561][ T0] ENABLING IO-APIC IRQs
[ 76.666405][ T0] init IO_APIC IRQs
[ 76.667124][ T0] apic 0 pin 0 not connected
[ 76.668088][ T0] IOAPIC[0]: Preconfigured routing entry (0-1 -> IRQ 1 Level:0 ActiveLow:0)
[ 76.669790][ T0] IOAPIC[0]: Preconfigured routing entry (0-2 -> IRQ 0 Level:0 ActiveLow:0)
[ 76.671482][ T0] IOAPIC[0]: Preconfigured routing entry (0-3 -> IRQ 3 Level:0 ActiveLow:0)
[ 76.673104][ T0] IOAPIC[0]: Preconfigured routing entry (0-4 -> IRQ 4 Level:0 ActiveLow:0)
[ 76.674780][ T0] IOAPIC[0]: Preconfigured routing entry (0-5 -> IRQ 5 Level:1 ActiveLow:0)
[ 76.676439][ T0] IOAPIC[0]: Preconfigured routing entry (0-6 -> IRQ 6 Level:0 ActiveLow:0)
[ 76.678132][ T0] IOAPIC[0]: Preconfigured routing entry (0-7 -> IRQ 7 Level:0 ActiveLow:0)
[ 76.679777][ T0] IOAPIC[0]: Preconfigured routing entry (0-8 -> IRQ 8 Level:0 ActiveLow:0)
[ 76.681451][ T0] IOAPIC[0]: Preconfigured routing entry (0-9 -> IRQ 9 Level:1 ActiveLow:0)
[ 76.683082][ T0] IOAPIC[0]: Preconfigured routing entry (0-10 -> IRQ 10 Level:1 ActiveLow:0)
[ 76.684704][ T0] IOAPIC[0]: Preconfigured routing entry (0-11 -> IRQ 11 Level:1 ActiveLow:0)
[ 76.686374][ T0] IOAPIC[0]: Preconfigured routing entry (0-12 -> IRQ 12 Level:0 ActiveLow:0)
[ 76.687950][ T0] IOAPIC[0]: Preconfigured routing entry (0-13 -> IRQ 13 Level:0 ActiveLow:0)
[ 76.689611][ T0] IOAPIC[0]: Preconfigured routing entry (0-14 -> IRQ 14 Level:0 ActiveLow:0)
[ 76.693333][ T0] IOAPIC[0]: Preconfigured routing entry (0-15 -> IRQ 15 Level:0 ActiveLow:0)
[ 76.694915][ T0] apic 0 pin 16 not connected
[ 76.695677][ T0] apic 0 pin 17 not connected
[ 76.696519][ T0] apic 0 pin 18 not connected
[ 76.697400][ T0] apic 0 pin 19 not connected
[ 76.698304][ T0] apic 0 pin 20 not connected
[ 76.699203][ T0] apic 0 pin 21 not connected
[ 76.700033][ T0] apic 0 pin 22 not connected
[ 76.700922][ T0] apic 0 pin 23 not connected
[ 76.701974][ T0] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 76.703326][ T0] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x26d349e8249, max_idle_ns: 440795288087 ns
[ 76.705550][ T0] Calibrating delay loop (skipped) preset value.. 5387.01 BogoMIPS (lpj=2693508)
[ 76.706526][ T0] pid_max: default: 4096 minimum: 301
[ 76.707836][ T0] LSM: Security Framework initializing
[ 76.708590][ T0] landlock: Up and running.
[ 76.709525][ T0] Yama: becoming mindful.
[ 76.710807][ T0] Mount-cache hash table entries: 32768 (order: 6, 262144 bytes, linear)
[ 76.711539][ T0] Mountpoint-cache hash table entries: 32768 (order: 6, 262144 bytes, linear)
Poking KASLR using RDTSC...
[ 76.717351][ T0] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 76.717533][ T0] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 76.718551][ T0] CPU: Intel Xeon E312xx (Sandy Bridge) (family: 0x6, model: 0x2a, stepping: 0x1)
[ 76.720528][ T0] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[ 76.721618][ T0] Spectre V2 : Mitigation: Full generic retpoline
[ 76.722525][ T0] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[ 76.723526][ T0] Speculative Store Bypass: Vulnerable
[ 76.724582][ T0] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
[ 76.755673][ T1] Running RCU-tasks wait API self tests
[ 76.861964][ T1] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only.
[ 76.862515][ T1] NMI watchdog: Perf NMI watchdog permanently disabled
[ 76.868172][ T9] Callback from call_rcu_tasks_trace() invoked.
[ 76.965944][ T8] Callback from call_rcu_tasks_rude() invoked.
[ 77.021205][ T1] allocated 201326592 bytes of page_ext
[ 77.026386][ T1] Node 0, zone DMA32: page owner found early allocated 0 pages
[ 77.075633][ T1] Node 0, zone Normal: page owner found early allocated 49924 pages
[ 77.078089][ T1] devtmpfs: initialized
[ 77.079998][ T1] x86/mm: Memory block size: 128MB
[ 77.136356][ T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[ 77.136559][ T1] futex hash table entries: 16 (order: -2, 1536 bytes, linear)
To reproduce:
# build kernel
cd linux
cp config-5.16.0-rc8-00597-g6be8b14abc0b .config
make HOSTCC=clang-14 CC=clang-14 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage modules
make HOSTCC=clang-14 CC=clang-14 ARCH=x86_64 INSTALL_MOD_PATH=<mod-install-dir> modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.
---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation
Thanks,
Oliver Sang
6 months, 4 weeks
- 1
- 0
[random] 2ee25b6968: stress-ng.getrandom.ops_per_sec 47.7% improvement
by kernel test robot
Greeting,
FYI, we noticed a 47.7% improvement of stress-ng.getrandom.ops_per_sec due to commit:
commit: 2ee25b6968b1b3c66ffa408de23d023c1bce81cf ("random: avoid superfluous call to RDRAND in CRNG extraction")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: stress-ng
on test machine: 96 threads 2 sockets Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz with 512G memory
with following parameters:
nr_threads: 100%
testtime: 60s
class: cpu
test: getrandom
cpufreq_governor: performance
ucode: 0x5003102
In addition to that, the commit also has significant impact on the following tests:
+------------------+---------------------------------------------------------------------------------+
| testcase: change | stress-ng: stress-ng.getrandom.ops_per_sec 33.0% improvement |
| test machine | 48 threads 2 sockets Intel(R) Xeon(R) CPU E5-2697 v2 @ 2.70GHz with 112G memory |
| test parameters | class=cpu |
| | cpufreq_governor=performance |
| | nr_threads=100% |
| | test=getrandom |
| | testtime=60s |
| | ucode=0x42e |
+------------------+---------------------------------------------------------------------------------+
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
sudo bin/lkp install job.yaml # job file is attached in this email
bin/lkp split-job --compatible job.yaml # generate the yaml file for lkp run
sudo bin/lkp run generated-yaml-file
# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.
=========================================================================================
class/compiler/cpufreq_governor/kconfig/nr_threads/rootfs/tbox_group/test/testcase/testtime/ucode:
cpu/gcc-9/performance/x86_64-rhel-8.3/100%/debian-10.4-x86_64-20200603.cgz/lkp-csl-2sp7/getrandom/stress-ng/60s/0x5003102
commit:
96562f2868 ("random: early initialization of ChaCha constants")
2ee25b6968 ("random: avoid superfluous call to RDRAND in CRNG extraction")
96562f286884e2db 2ee25b6968b1b3c66ffa408de23
---------------- ---------------------------
%stddev %change %stddev
\ | \
1582098 ± 2% +47.7% 2337043 stress-ng.getrandom.ops
26367 ± 2% +47.7% 38950 stress-ng.getrandom.ops_per_sec
36932 +2.3% 37791 stress-ng.time.involuntary_context_switches
3.365e+08 ± 3% +24.5% 4.191e+08 ± 7% cpuidle..time
402.02 -1.8% 394.71 pmeter.Average_Active_Power
59.00 -4.0% 56.67 turbostat.PkgTmp
211108 -1.0% 209101 vmstat.system.in
4.66 +1.9 6.53 ± 10% mpstat.cpu.all.idle%
0.50 +0.0 0.54 ± 2% mpstat.cpu.all.irq%
0.14 ± 3% -0.0 0.12 ± 14% mpstat.cpu.all.usr%
191308 ± 11% -55.2% 85772 ± 3% numa-numastat.node0.local_node
249395 ± 2% -30.7% 172877 numa-numastat.node0.numa_hit
58087 ± 39% +50.0% 87104 numa-numastat.node0.other_node
173851 ± 12% +58.9% 276208 numa-numastat.node1.local_node
202838 ± 3% +36.2% 276198 numa-numastat.node1.numa_hit
7408 +5.8% 7840 proc-vmstat.nr_active_anon
2668 ± 3% -5.2% 2528 proc-vmstat.nr_page_table_pages
11807 +3.7% 12242 proc-vmstat.nr_shmem
7408 +5.8% 7840 proc-vmstat.nr_zone_active_anon
21247 +3.5% 21981 proc-vmstat.pgactivate
75695 ± 6% -26.3% 55749 ± 22% numa-meminfo.node0.KReclaimable
75695 ± 6% -26.3% 55749 ± 22% numa-meminfo.node0.SReclaimable
182572 ± 5% -14.6% 155921 numa-meminfo.node0.Slab
26308 ± 17% +74.9% 46018 ± 25% numa-meminfo.node1.KReclaimable
1145005 ± 7% +67.8% 1921879 ± 45% numa-meminfo.node1.MemUsed
26308 ± 17% +74.9% 46018 ± 25% numa-meminfo.node1.SReclaimable
111636 ± 7% +24.4% 138864 numa-meminfo.node1.Slab
18923 ± 6% -26.4% 13936 ± 22% numa-vmstat.node0.nr_slab_reclaimable
3899 ± 4% +8.3% 4221 ± 5% numa-vmstat.node0.numa_interleave
1525275 ± 7% -14.6% 1302321 ± 12% numa-vmstat.node0.numa_local
59275 ± 41% +53.7% 91128 numa-vmstat.node0.numa_other
6576 ± 17% +74.9% 11500 ± 25% numa-vmstat.node1.nr_slab_reclaimable
4170 ± 4% -7.4% 3860 ± 6% numa-vmstat.node1.numa_interleave
722855 ± 17% +30.3% 941837 ± 17% numa-vmstat.node1.numa_local
67164 ± 35% -47.5% 35243 numa-vmstat.node1.numa_other
98.00 -0.1 97.91 perf-profile.calltrace.cycles-pp._extract_crng.urandom_read_nowarn.do_syscall_64.entry_SYSCALL_64_after_hwframe.getrandom
0.69 ± 7% +0.7 1.36 perf-profile.calltrace.cycles-pp.chacha_block_generic._extract_crng.urandom_read_nowarn.do_syscall_64.entry_SYSCALL_64_after_hwframe
0.17 ±141% +1.0 1.12 perf-profile.calltrace.cycles-pp.chacha_permute.chacha_block_generic._extract_crng.urandom_read_nowarn.do_syscall_64
98.76 -0.1 98.69 perf-profile.children.cycles-pp._extract_crng
0.08 ± 6% +0.0 0.11 ± 4% perf-profile.children.cycles-pp.copy_user_enhanced_fast_string
0.00 +0.1 0.05 perf-profile.children.cycles-pp.__might_fault
0.12 ± 6% +0.1 0.18 perf-profile.children.cycles-pp._copy_to_user
0.48 ± 6% +0.7 1.14 perf-profile.children.cycles-pp.chacha_permute
0.69 ± 7% +0.7 1.37 perf-profile.children.cycles-pp.chacha_block_generic
0.89 ± 10% -0.8 0.10 ± 8% perf-profile.self.cycles-pp._extract_crng
0.08 ± 6% +0.0 0.11 perf-profile.self.cycles-pp.copy_user_enhanced_fast_string
0.26 ± 5% +0.1 0.40 ± 2% perf-profile.self.cycles-pp._raw_spin_lock_irqsave
0.48 ± 6% +0.7 1.14 perf-profile.self.cycles-pp.chacha_permute
4.65 ± 4% +24.6% 5.79 ± 2% perf-stat.i.MPKI
5.33e+09 +1.4% 5.402e+09 perf-stat.i.branch-instructions
0.95 ± 9% -0.2 0.70 ± 10% perf-stat.i.cache-miss-rate%
1.189e+08 ± 3% +35.5% 1.611e+08 ± 3% perf-stat.i.cache-references
9.83 -8.1% 9.03 perf-stat.i.cpi
557463 -3.3% 539225 perf-stat.i.cycles-between-cache-misses
5.913e+09 +5.5% 6.241e+09 perf-stat.i.dTLB-loads
6.913e+08 +37.7% 9.518e+08 perf-stat.i.dTLB-stores
2.548e+10 +8.4% 2.762e+10 perf-stat.i.instructions
0.13 +6.5% 0.13 perf-stat.i.ipc
12.24 -1.7% 12.03 perf-stat.i.major-faults
125.53 +5.8% 132.84 perf-stat.i.metric.M/sec
80.14 +2.6 82.73 perf-stat.i.node-load-miss-rate%
55782 -10.1% 50128 perf-stat.i.node-loads
57.92 ± 6% +23.3 81.21 perf-stat.i.node-store-miss-rate%
49315 ± 6% +19.2% 58807 ± 3% perf-stat.i.node-store-misses
39223 ± 4% -44.3% 21849 ± 2% perf-stat.i.node-stores
4.67 ± 4% +24.9% 5.83 ± 3% perf-stat.overall.MPKI
0.57 -0.2 0.42 ± 5% perf-stat.overall.cache-miss-rate%
10.05 -8.0% 9.24 perf-stat.overall.cpi
0.00 ± 2% -0.0 0.00 ± 13% perf-stat.overall.dTLB-store-miss-rate%
0.10 +8.8% 0.11 perf-stat.overall.ipc
70.08 +2.0 72.05 perf-stat.overall.node-load-miss-rate%
55.46 ± 5% +17.3 72.72 perf-stat.overall.node-store-miss-rate%
5.246e+09 +1.4% 5.318e+09 perf-stat.ps.branch-instructions
1.171e+08 ± 3% +35.4% 1.586e+08 ± 3% perf-stat.ps.cache-references
5.821e+09 +5.5% 6.143e+09 perf-stat.ps.dTLB-loads
6.808e+08 +37.7% 9.371e+08 perf-stat.ps.dTLB-stores
2.508e+10 +8.4% 2.719e+10 perf-stat.ps.instructions
57596 -11.8% 50795 perf-stat.ps.node-loads
48478 ± 7% +19.4% 57879 ± 3% perf-stat.ps.node-store-misses
38847 ± 4% -44.1% 21704 ± 2% perf-stat.ps.node-stores
1.581e+12 +9.6% 1.733e+12 perf-stat.total.instructions
***************************************************************************************************
lkp-ivb-2ep1: 48 threads 2 sockets Intel(R) Xeon(R) CPU E5-2697 v2 @ 2.70GHz with 112G memory
=========================================================================================
class/compiler/cpufreq_governor/kconfig/nr_threads/rootfs/tbox_group/test/testcase/testtime/ucode:
cpu/gcc-9/performance/x86_64-rhel-8.3/100%/debian-10.4-x86_64-20200603.cgz/lkp-ivb-2ep1/getrandom/stress-ng/60s/0x42e
commit:
96562f2868 ("random: early initialization of ChaCha constants")
2ee25b6968 ("random: avoid superfluous call to RDRAND in CRNG extraction")
96562f286884e2db 2ee25b6968b1b3c66ffa408de23
---------------- ---------------------------
%stddev %change %stddev
\ | \
1749360 +33.0% 2327260 stress-ng.getrandom.ops
29155 +33.0% 38787 stress-ng.getrandom.ops_per_sec
79229 ± 4% +6.8% 84630 ± 3% meminfo.AnonHugePages
9206 ± 3% -5.5% 8699 ± 2% proc-vmstat.pgactivate
112056 ± 3% +8.1% 121114 ± 3% softirqs.SCHED
35.12 +2.0% 35.82 boot-time.boot
1458 +2.1% 1489 boot-time.idle
1.81e+08 ± 12% +27.3% 2.304e+08 ± 13% cpuidle..time
401544 ± 14% +29.3% 519074 ± 12% cpuidle..usage
129917 ± 4% -7.8% 119774 ± 5% numa-numastat.node0.local_node
126626 ± 3% +8.4% 137254 ± 5% numa-numastat.node1.local_node
1632 ± 2% +3.9% 1695 vmstat.system.cs
104682 -1.0% 103645 vmstat.system.in
4.99 ± 13% +1.8 6.82 ± 9% mpstat.cpu.all.idle%
0.85 ± 3% +0.1 0.94 ± 4% mpstat.cpu.all.irq%
0.01 ± 7% +0.0 0.02 ± 8% mpstat.cpu.all.soft%
11520 ± 96% -86.7% 1534 ± 87% numa-meminfo.node1.AnonHugePages
52249 ± 86% -72.2% 14547 ± 25% numa-meminfo.node1.AnonPages
71509 ± 64% -53.2% 33434 ± 13% numa-meminfo.node1.AnonPages.max
2428 ± 2% +12.8% 2740 ± 7% numa-vmstat.node0.numa_interleave
13085 ± 86% -72.3% 3628 ± 25% numa-vmstat.node1.nr_anon_pages
2831 ± 2% -11.4% 2509 ± 8% numa-vmstat.node1.numa_interleave
137674 ± 70% +87.2% 257735 ± 13% turbostat.C1E
200554 ± 13% +22.4% 245492 ± 13% turbostat.C6
4.55 ± 7% +1.4 6.00 ± 8% turbostat.C6%
2.49 ± 22% +40.9% 3.51 ± 6% turbostat.CPU%c6
1.05 ± 3% +61.6% 1.70 ± 24% turbostat.Pkg%pc2
0.03 ± 77% +475.0% 0.15 ± 54% turbostat.Pkg%pc6
71.00 -3.8% 68.33 turbostat.PkgTmp
2.18 ± 6% +40.0% 3.05 ± 15% perf-stat.i.MPKI
0.34 ± 10% +0.2 0.51 ± 15% perf-stat.i.branch-miss-rate%
62441682 ± 3% +20.3% 75094122 ± 2% perf-stat.i.cache-references
1387 +3.2% 1432 ± 2% perf-stat.i.context-switches
4.27 -4.0% 4.10 perf-stat.i.cpi
1.366e+11 -1.2% 1.349e+11 perf-stat.i.cpu-cycles
7.358e+09 +2.4% 7.535e+09 perf-stat.i.dTLB-loads
1293946 ± 16% +32.9% 1719408 ± 8% perf-stat.i.dTLB-store-misses
7.417e+08 +27.5% 9.46e+08 perf-stat.i.dTLB-stores
278891 +12.7% 314323 ± 4% perf-stat.i.iTLB-load-misses
3.144e+10 +4.1% 3.274e+10 perf-stat.i.instructions
184251 -14.1% 158318 ± 2% perf-stat.i.instructions-per-iTLB-miss
10.79 -2.7% 10.50 perf-stat.i.major-faults
2.85 -1.2% 2.81 perf-stat.i.metric.GHz
63.24 ± 6% +33.4% 84.34 ± 7% perf-stat.i.metric.K/sec
310.37 +2.5% 318.16 perf-stat.i.metric.M/sec
1.99 ± 3% +15.5% 2.29 ± 2% perf-stat.overall.MPKI
4.34 -5.1% 4.12 perf-stat.overall.cpi
112722 -7.4% 104351 ± 5% perf-stat.overall.instructions-per-iTLB-miss
0.23 +5.4% 0.24 perf-stat.overall.ipc
61452519 ± 3% +20.3% 73919165 ± 2% perf-stat.ps.cache-references
1365 +3.3% 1410 ± 2% perf-stat.ps.context-switches
1.344e+11 -1.2% 1.328e+11 perf-stat.ps.cpu-cycles
7.241e+09 +2.4% 7.416e+09 perf-stat.ps.dTLB-loads
1273486 ± 16% +32.9% 1692449 ± 8% perf-stat.ps.dTLB-store-misses
7.299e+08 +27.6% 9.312e+08 perf-stat.ps.dTLB-stores
274494 +12.8% 309549 ± 4% perf-stat.ps.iTLB-load-misses
3.094e+10 +4.1% 3.223e+10 perf-stat.ps.instructions
10.63 -2.3% 10.38 perf-stat.ps.major-faults
1.966e+12 +5.8% 2.08e+12 perf-stat.total.instructions
97.38 -0.3 97.12 perf-profile.calltrace.cycles-pp._extract_crng.urandom_read_nowarn.do_syscall_64.entry_SYSCALL_64_after_hwframe.getrandom
94.02 -0.1 93.93 perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock_irqsave._extract_crng.urandom_read_nowarn.do_syscall_64
99.82 -0.0 99.80 perf-profile.calltrace.cycles-pp.urandom_read_nowarn.do_syscall_64.entry_SYSCALL_64_after_hwframe.getrandom
99.86 -0.0 99.84 perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe.getrandom
99.86 -0.0 99.84 perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe.getrandom
1.31 +0.7 2.03 ± 6% perf-profile.calltrace.cycles-pp.chacha_permute.chacha_block_generic._extract_crng.urandom_read_nowarn.do_syscall_64
1.74 +0.9 2.60 perf-profile.calltrace.cycles-pp.chacha_block_generic._extract_crng.urandom_read_nowarn.do_syscall_64.entry_SYSCALL_64_after_hwframe
98.15 -0.3 97.89 perf-profile.children.cycles-pp._extract_crng
95.50 -0.1 95.42 perf-profile.children.cycles-pp.native_queued_spin_lock_slowpath
99.84 -0.0 99.82 perf-profile.children.cycles-pp.urandom_read_nowarn
99.92 -0.0 99.90 perf-profile.children.cycles-pp.do_syscall_64
99.92 -0.0 99.90 perf-profile.children.cycles-pp.entry_SYSCALL_64_after_hwframe
0.12 +0.0 0.13 perf-profile.children.cycles-pp.tick_sched_handle
0.12 +0.0 0.13 perf-profile.children.cycles-pp.update_process_times
0.13 +0.0 0.14 ± 3% perf-profile.children.cycles-pp.tick_sched_timer
0.12 ± 6% +0.0 0.14 ± 5% perf-profile.children.cycles-pp.copy_user_enhanced_fast_string
0.31 ± 2% +0.0 0.33 ± 2% perf-profile.children.cycles-pp.asm_sysvec_apic_timer_interrupt
0.09 ± 9% +0.0 0.12 ± 4% perf-profile.children.cycles-pp.__check_object_size
0.40 ± 2% +0.0 0.43 perf-profile.children.cycles-pp._raw_spin_unlock_irqrestore
0.00 +0.1 0.05 perf-profile.children.cycles-pp.check_stack_object
0.12 ± 13% +0.1 0.23 ± 2% perf-profile.children.cycles-pp.__might_resched
0.18 ± 9% +0.1 0.31 perf-profile.children.cycles-pp.__might_fault
0.35 ± 5% +0.2 0.50 perf-profile.children.cycles-pp._copy_to_user
1.32 +0.7 2.05 ± 6% perf-profile.children.cycles-pp.chacha_permute
1.76 +0.9 2.63 perf-profile.children.cycles-pp.chacha_block_generic
1.25 ± 4% -1.1 0.12 ± 4% perf-profile.self.cycles-pp._extract_crng
95.50 -0.1 95.42 perf-profile.self.cycles-pp.native_queued_spin_lock_slowpath
0.06 ± 7% +0.0 0.08 ± 6% perf-profile.self.cycles-pp._raw_spin_unlock_irqrestore
0.05 ± 8% +0.0 0.07 perf-profile.self.cycles-pp.urandom_read_nowarn
0.11 ± 4% +0.0 0.13 ± 3% perf-profile.self.cycles-pp.copy_user_enhanced_fast_string
0.05 +0.0 0.07 ± 12% perf-profile.self.cycles-pp.__check_object_size
0.00 +0.1 0.05 ± 8% perf-profile.self.cycles-pp.__might_fault
0.12 ± 13% +0.1 0.22 ± 2% perf-profile.self.cycles-pp.__might_resched
0.39 ± 3% +0.1 0.50 ± 6% perf-profile.self.cycles-pp._raw_spin_lock_irqsave
0.44 +0.1 0.58 ± 18% perf-profile.self.cycles-pp.chacha_block_generic
1.32 +0.7 2.05 ± 6% perf-profile.self.cycles-pp.chacha_permute
Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.
---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation
Thanks,
Oliver Sang
6 months, 4 weeks
- 1
- 0
[xfs] 7993f1a431: xfstests.xfs.308.fail
by kernel test robot
Greeting,
FYI, we noticed the following commit (built with gcc-9):
commit: 7993f1a431bc5271369d359941485a9340658ac3 ("xfs: only run COW extent recovery when there are no live extents")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: xfstests
version: xfstests-x86_64-972d710-1_20211228
with following parameters:
disk: 4HDD
fs: xfs
test: xfs-reflink-15
ucode: 0x28
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: 8 threads 1 sockets Intel(R) Core(TM) i7-4790 v3 @ 3.60GHz with 6G 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 <oliver.sang(a)intel.com>
2022-01-07 19:55:40 export TEST_DIR=/fs/sda1
2022-01-07 19:55:40 export TEST_DEV=/dev/sda1
2022-01-07 19:55:40 export FSTYP=xfs
2022-01-07 19:55:40 export SCRATCH_MNT=/fs/scratch
2022-01-07 19:55:40 mkdir /fs/scratch -p
2022-01-07 19:55:40 export SCRATCH_DEV=/dev/sda4
2022-01-07 19:55:40 export SCRATCH_LOGDEV=/dev/sda2
2022-01-07 19:55:40 export SCRATCH_XFS_LIST_METADATA_FIELDS=u3.sfdir3.hdr.parent.i4
2022-01-07 19:55:40 export SCRATCH_XFS_LIST_FUZZ_VERBS=random
2022-01-07 19:55:40 export MKFS_OPTIONS=-mreflink=1
2022-01-07 19:55:40 sed "s:^:xfs/:" //lkp/benchmarks/xfstests/tests/xfs-reflink-15
2022-01-07 19:55:40 ./check xfs/307 xfs/308 xfs/309 xfs/312 xfs/313 xfs/315 xfs/316 xfs/319
FSTYP -- xfs (debug)
PLATFORM -- Linux/x86_64 lkp-hsw-d02 5.16.0-rc5-00004-g7993f1a431bc #1 SMP Wed Dec 29 06:35:34 CST 2021
MKFS_OPTIONS -- -f -mreflink=1 /dev/sda4
MOUNT_OPTIONS -- /dev/sda4 /fs/scratch
xfs/307 4s
xfs/308 _check_xfs_filesystem: filesystem on /dev/sda4 is inconsistent (r)
(see /lkp/benchmarks/xfstests/results//xfs/308.full for details)
- output mismatch (see /lkp/benchmarks/xfstests/results//xfs/308.out.bad)
--- tests/xfs/308.out 2021-12-31 10:10:52.000000000 +0000
+++ /lkp/benchmarks/xfstests/results//xfs/308.out.bad 2022-01-07 19:55:49.909107057 +0000
@@ -10,4 +10,6 @@
leftover CoW extent (NR/NR) len NR
Mount filesystem
Look for no more leftover warning in xfs_check
+leftover CoW extent (NR/NR) len NR
Look for no more leftover warning in xfs_repair
+leftover CoW extent (NR/NR) len NR
...
(Run 'diff -u /lkp/benchmarks/xfstests/tests/xfs/308.out /lkp/benchmarks/xfstests/results//xfs/308.out.bad' to see the entire diff)
xfs/309 19s
xfs/312 4s
xfs/313 5s
xfs/315 4s
xfs/316 4s
xfs/319 5s
Ran: xfs/307 xfs/308 xfs/309 xfs/312 xfs/313 xfs/315 xfs/316 xfs/319
Failures: xfs/308
Failed 1 of 8 tests
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
sudo bin/lkp install job.yaml # job file is attached in this email
bin/lkp split-job --compatible job.yaml # generate the yaml file for lkp run
sudo bin/lkp run generated-yaml-file
# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.
---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation
Thanks,
Oliver Sang
6 months, 4 weeks
- 1
- 0
0-DAY CI LINUX KERNEL PERFORMANCE REPORT (V5.16)
by Si, Beibei
Hi all,
0-Day CI Linux Kernel Performance Report (v5.16) has updated. Welcome your feedback.
Here’s the link: https://01.org/lkp/blogs/carel/2022/0-day-ci-linux-kernel-performance-rep...
Previous report can refer to: https://01.org/group/3977/blogs
BR
Carel
7 months
- 1
- 0
[mm/oom_kill] b93acee44e: BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c
by kernel test robot
Greeting,
FYI, we noticed the following commit (built with gcc-9):
commit: b93acee44e1a2b541ffe5ae1dda6be3d6f65987f ("mm/oom_kill: wake futex waiters before annihilating victim shared mutex")
url: https://github.com/0day-ci/linux/commits/Yang-Li/net-phy-micrel-use-min-m...
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu Icelake-Server -smp 4 -m 16G
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 <oliver.sang(a)intel.com>
[ 61.631030][ T538] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:584
[ 61.632850][ T538] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 538, name: kworker/u8:5
[ 61.634593][ T538] preempt_count: 1, expected: 0
[ 61.635532][ T538] RCU nest depth: 0, expected: 0
[ 61.636479][ T538] 2 locks held by kworker/u8:5/538:
[ 61.637447][ T538] #0: c5515ef8 (oom_lock){+.+.}-{3:3}, at: __alloc_pages_slowpath+0x4f7/0xe40
[ 61.639340][ T538] #1: f591cafc (&p->alloc_lock){+.+.}-{2:2}, at: find_lock_task_mm (mm/oom_kill.c:145)
[ 61.641023][ T538] Preemption disabled at:
[ 61.641037][ T538] find_lock_task_mm (mm/oom_kill.c:145)
[ 61.642909][ T538] CPU: 2 PID: 538 Comm: kworker/u8:5 Not tainted 5.16.0-rc6-00051-gb93acee44e1a #2
[ 61.644587][ T538] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[ 61.646331][ T538] Call Trace:
[ 61.646977][ T538] dump_stack_lvl (lib/dump_stack.c:107)
[ 61.647873][ T538] dump_stack (lib/dump_stack.c:114)
[ 61.648692][ T538] __might_resched.cold (kernel/sched/core.c:9539)
[ 61.649673][ T538] ? find_lock_task_mm (mm/oom_kill.c:145)
[ 61.650669][ T538] __might_sleep (kernel/sched/core.c:9468 (discriminator 24))
[ 61.651544][ T538] __mutex_lock (include/linux/kernel.h:101 kernel/locking/mutex.c:584 kernel/locking/mutex.c:740)
[ 61.652306][ T538] ? check_preemption_disabled (lib/smp_processor_id.c:16)
[ 61.655532][ T538] ? check_preemption_disabled (lib/smp_processor_id.c:16)
[ 61.656539][ T538] mutex_lock_nested (kernel/locking/mutex.c:793)
[ 61.657421][ T538] ? futex_cleanup_begin (kernel/futex/core.c:1084)
[ 61.658371][ T538] futex_cleanup_begin (kernel/futex/core.c:1084)
[ 61.659339][ T538] futex_exit_release (kernel/futex/core.c:1124)
[ 61.660237][ T538] __oom_kill_process (mm/oom_kill.c:899)
[ 61.661238][ T538] oom_kill_process (mm/oom_kill.c:1008)
[ 61.662178][ T538] out_of_memory (mm/oom_kill.c:1131 (discriminator 4))
[ 61.663049][ T538] __alloc_pages_slowpath+0xd7f/0xe40
[ 61.664287][ T538] __alloc_pages (mm/page_alloc.c:5382)
[ 61.665183][ T538] cache_grow_begin+0x247/0x320
[ 61.666307][ T538] cache_alloc_refill (mm/slab.c:2965)
[ 61.667261][ T538] ? check_preemption_disabled (lib/smp_processor_id.c:16)
[ 61.668392][ T538] kmem_cache_alloc (mm/slab.c:3048 mm/slab.c:3031 mm/slab.c:3292 mm/slab.c:3316 mm/slab.c:3507)
[ 61.669315][ T538] getname_kernel (fs/namei.c:227)
[ 61.670208][ T538] kernel_execve (include/linux/err.h:36 fs/exec.c:1940)
[ 61.671077][ T538] call_usermodehelper_exec_async (kernel/umh.c:116)
[ 61.672208][ T538] ? umh_complete (kernel/umh.c:67)
[ 61.673026][ T538] ret_from_fork (arch/x86/entry/entry_32.S:775)
[ 61.673926][ T538]
[ 61.674318][ T538] =============================
[ 61.675084][ T538] [ BUG: Invalid wait context ]
[ 61.675857][ T538] 5.16.0-rc6-00051-gb93acee44e1a #2 Tainted: G W
[ 61.677049][ T538] -----------------------------
[ 61.677824][ T538] kworker/u8:5/538 is trying to lock:
[ 61.678698][ T538] f591d154 (&tsk->futex_exit_mutex){+.+.}-{3:3}, at: futex_cleanup_begin (kernel/futex/core.c:1084)
[ 61.680223][ T538] other info that might help us debug this:
[ 61.681201][ T538] context-{4:4}
[ 61.681786][ T538] 2 locks held by kworker/u8:5/538:
[ 61.682660][ T538] #0: c5515ef8 (oom_lock){+.+.}-{3:3}, at: __alloc_pages_slowpath+0x4f7/0xe40
[ 61.684302][ T538] #1: f591cafc (&p->alloc_lock){+.+.}-{2:2}, at: find_lock_task_mm (mm/oom_kill.c:145)
[ 61.685872][ T538] stack backtrace:
[ 61.686492][ T538] CPU: 2 PID: 538 Comm: kworker/u8:5 Tainted: G W 5.16.0-rc6-00051-gb93acee44e1a #2
[ 61.688196][ T538] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[ 61.689731][ T538] Call Trace:
[ 61.690294][ T538] dump_stack_lvl (lib/dump_stack.c:107)
[ 61.691025][ T538] dump_stack (lib/dump_stack.c:114)
[ 61.691730][ T538] __lock_acquire.cold (kernel/locking/lockdep.c:4680 kernel/locking/lockdep.c:4739 kernel/locking/lockdep.c:4977)
[ 61.692585][ T538] ? check_preemption_disabled (lib/smp_processor_id.c:16)
[ 61.693533][ T538] lock_acquire (kernel/locking/lockdep.c:438 kernel/locking/lockdep.c:5639 kernel/locking/lockdep.c:5602)
[ 61.694275][ T538] ? futex_cleanup_begin (kernel/futex/core.c:1084)
[ 61.695139][ T538] ? __might_resched.cold (kernel/sched/core.c:9539)
[ 61.696045][ T538] ? find_lock_task_mm (mm/oom_kill.c:145)
[ 61.696868][ T538] __mutex_lock (arch/x86/include/asm/atomic.h:29 include/linux/atomic/atomic-long.h:523 include/linux/atomic/atomic-instrumented.h:1184 kernel/locking/mutex.c:104 kernel/locking/mutex.c:149 kernel/locking/mutex.c:609 kernel/locking/mutex.c:740)
[ 61.697523][ T538] ? futex_cleanup_begin (kernel/futex/core.c:1084)
[ 61.698389][ T538] ? check_preemption_disabled (lib/smp_processor_id.c:16)
[ 61.699287][ T538] ? check_preemption_disabled (lib/smp_processor_id.c:16)
[ 61.700190][ T538] mutex_lock_nested (kernel/locking/mutex.c:793)
[ 61.700975][ T538] ? futex_cleanup_begin (kernel/futex/core.c:1084)
[ 61.701816][ T538] futex_cleanup_begin (kernel/futex/core.c:1084)
[ 61.702621][ T538] futex_exit_release (kernel/futex/core.c:1124)
[ 61.703363][ T538] __oom_kill_process (mm/oom_kill.c:899)
[ 61.704227][ T538] oom_kill_process (mm/oom_kill.c:1008)
[ 61.705003][ T538] out_of_memory (mm/oom_kill.c:1131 (discriminator 4))
[ 61.705784][ T538] __alloc_pages_slowpath+0xd7f/0xe40
[ 61.706829][ T538] __alloc_pages (mm/page_alloc.c:5382)
[ 61.707608][ T538] cache_grow_begin+0x247/0x320
[ 61.708602][ T538] cache_alloc_refill (mm/slab.c:2965)
[ 61.709435][ T538] ? check_preemption_disabled (lib/smp_processor_id.c:16)
[ 61.710385][ T538] kmem_cache_alloc (mm/slab.c:3048 mm/slab.c:3031 mm/slab.c:3292 mm/slab.c:3316 mm/slab.c:3507)
[ 61.711194][ T538] getname_kernel (fs/namei.c:227)
[ 61.711964][ T538] kernel_execve (include/linux/err.h:36 fs/exec.c:1940)
[ 61.712710][ T538] call_usermodehelper_exec_async (kernel/umh.c:116)
[ 61.713660][ T538] ? umh_complete (kernel/umh.c:67)
[ 61.714361][ T538] ret_from_fork (arch/x86/entry/entry_32.S:775)
[ 61.715202][ T538] Out of memory: Killed process 458 (systemd-journal) total-vm:15160kB, anon-rss:344kB, file-rss:3536kB, shmem-rss:1384kB, UID:0 pgtables:20kB oom_score_adj:0
[ 61.831730][ T41] oom_reaper: reaped process 458 (systemd-journal), now anon-rss:0kB, file-rss:0kB, shmem-rss:1384kB
[ 62.198664][ T539] _warn_unseeded_randomness: 3 callbacks suppressed
[ 62.198675][ T539] random: get_random_u32 called from arch_rnd+0x19/0x30 with crng_init=1
[ 62.198691][ T539] random: get_random_u32 called from randomize_stack_top+0x35/0x50 with crng_init=1
[ 62.198703][ T539] random: get_random_u32 called from arch_align_stack+0x35/0x50 with crng_init=1
Stopping Flush Journal to Persistent Storage...
[ OK ] Stopped Journal Service.
Starting Journal Service...
[ 71.972603][ T540] random: get_random_u32 called from arch_rnd+0x19/0x30 with crng_init=1
[ 71.972628][ T540] random: get_random_u32 called from randomize_stack_top+0x35/0x50 with crng_init=1
[ 71.972635][ T540] random: get_random_u32 called from arch_align_stack+0x35/0x50 with crng_init=1
[ OK ] Started Journal Service.
Starting Flush Journal to Persistent Storage...
[ OK ] Reached target System Initialization.
[ OK ] Listening on D-Bus System Message Bus Socket.
[ OK ] Reached target Sockets.
[ OK ] Started Daily apt download activities.
[ OK ] Started Daily Cleanup of Temporary Directories.
[ OK ] Started Daily apt upgrade and clean activities.
[ OK ] Reached target Timers.
[ OK ] Reached target Basic System.
Starting LKP bootstrap...
Startin[ 77.266134][ T544] _warn_unseeded_randomness: 17 callbacks suppressed
g /etc/rc.local [ 77.266144][ T544] random: get_random_u32 called from arch_rnd+0x19/0x30 with crng_init=1
Compatibility...[ 77.266160][ T544] random: get_random_u32 called from randomize_stack_top+0x35/0x50 with crng_init=1
[ 77.266173][ T544] random: get_random_u32 called from arch_align_stack+0x35/0x50 with crng_init=1
Starting LSB: Execute the kexec -e command to reboot system...
Starting LSB: Start and stop bmc-watchdog...
Starting OpenBSD Secure Shell server...
[ 87.529761][ T560] _warn_unseeded_randomness: 70 callbacks suppressed
[ 87.529769][ T560] random: get_random_u32 called from arch_rnd+0x19/0x30 with crng_init=1
[ 87.529779][ T560] random: get_random_u32 called from randomize_stack_top+0x35/0x50 with crng_init=1
[ 87.529787][ T560] random: get_random_u32 called from arch_align_stack+0x35/0x50 with crng_init=1
Starting System Logging Service...
[ 87.475599] rc.local[545]: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/lkp/lkp/src/bin
[ 87.588377][ T523] systemd-udevd invoked oom-killer: gfp_mask=0x40cc0(GFP_KERNEL|__GFP_COMP), order=0, oom_score_adj=0
[ 87.590235][ T523] CPU: 1 PID: 523 Comm: systemd-udevd Tainted: G W 5.16.0-rc6-00051-gb93acee44e1a #2
[ 87.592034][ T523] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[ 87.593451][ T523] Call Trace:
[ 87.593965][ T523] dump_stack_lvl (lib/dump_stack.c:107)
[ 87.594666][ T523] dump_stack (lib/dump_stack.c:114)
[ 87.595338][ T523] dump_header (mm/oom_kill.c:465)
[ 87.596051][ T523] ? ___ratelimit (lib/ratelimit.c:69)
[ 87.596794][ T523] oom_kill_process.cold (mm/oom_kill.c:994)
[ 87.597533][ T523] out_of_memory (mm/oom_kill.c:1131 (discriminator 4))
[ 87.598258][ T523] __alloc_pages_slowpath+0xd7f/0xe40
[ 87.599300][ T523] __alloc_pages (mm/page_alloc.c:5382)
[ 87.600063][ T523] cache_grow_begin+0x247/0x320
[ 87.600974][ T523] cache_alloc_refill (mm/slab.c:2965)
[ 87.601757][ T523] ? check_preemption_disabled (lib/smp_processor_id.c:16)
[ 87.602703][ T523] kmem_cache_alloc (mm/slab.c:3048 mm/slab.c:3031 mm/slab.c:3292 mm/slab.c:3316 mm/slab.c:3507)
[ 87.603512][ T523] ? import_iovec (lib/iov_iter.c:2015)
[ 87.604274][ T523] getname_flags (include/linux/audit.h:323 include/linux/audit.h:363 fs/namei.c:134)
[ 87.605141][ T523] ? kfree (mm/slab.c:3790)
[ 87.605789][ T523] getname_flags (fs/namei.c:204)
[ 87.606502][ T523] getname (fs/namei.c:218)
[ 87.607132][ T523] do_sys_openat2 (include/linux/err.h:36 fs/open.c:1207)
[ 87.607931][ T523] ? dput_to_list (include/linux/rcupdate.h:689 fs/dcache.c:909)
[ 87.608737][ T523] do_sys_open (fs/open.c:1229)
[ 87.609451][ T523] __ia32_sys_open (fs/open.c:1232)
[ 87.610228][ T523] do_int80_syscall_32 (arch/x86/entry/common.c:112 arch/x86/entry/common.c:132)
[ 87.611061][ T523] ? check_preemption_disabled (lib/smp_processor_id.c:16)
[ 87.612021][ T523] ? rcu_read_lock_held_common (kernel/rcu/update.c:104)
[ 87.612977][ T523] ? rcu_read_lock_sched_held (kernel/rcu/update.c:123)
[ 87.613916][ T523] ? trace_irq_enable (include/trace/events/preemptirq.h:40)
[ 87.614765][ T523] ? syscall_exit_to_user_mode (kernel/entry/common.c:303)
[ 87.615723][ T523] ? do_int80_syscall_32 (arch/x86/entry/common.c:136)
[ 87.616495][ T523] ? do_int80_syscall_32 (arch/x86/entry/common.c:136)
[ 87.617348][ T523] ? do_int80_syscall_32 (arch/x86/entry/common.c:136)
[ 87.618209][ T523] ? do_int80_syscall_32 (arch/x86/entry/common.c:136)
[ 87.619046][ T523] ? sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1097)
[ 87.620016][ T523] entry_INT80_32 (arch/x86/entry/entry_32.S:981)
[ 87.620774][ T523] EIP: 0xb7f33a02
[ 87.621392][ T523] Code: 95 01 00 05 25 36 02 00 83 ec 14 8d 80 e8 99 ff ff 50 6a 02 e8 1f ff 00 00 c7 04 24 7f 00 00 00 e8 7e 87 01 00 66 90 90 cd 80 <c3> 8d b6 00 00 00 00 8d bc 27 00 00 00 00 8b 1c 24 c3 8d b6 00 00
All code
========
0: 95 xchg %eax,%ebp
1: 01 00 add %eax,(%rax)
3: 05 25 36 02 00 add $0x23625,%eax
8: 83 ec 14 sub $0x14,%esp
b: 8d 80 e8 99 ff ff lea -0x6618(%rax),%eax
11: 50 push %rax
12: 6a 02 pushq $0x2
14: e8 1f ff 00 00 callq 0xff38
19: c7 04 24 7f 00 00 00 movl $0x7f,(%rsp)
20: e8 7e 87 01 00 callq 0x187a3
25: 66 90 xchg %ax,%ax
27: 90 nop
28: cd 80 int $0x80
2a:* c3 retq <-- trapping instruction
2b: 8d b6 00 00 00 00 lea 0x0(%rsi),%esi
31: 8d bc 27 00 00 00 00 lea 0x0(%rdi,%riz,1),%edi
38: 8b 1c 24 mov (%rsp),%ebx
3b: c3 retq
3c: 8d .byte 0x8d
3d: b6 00 mov $0x0,%dh
...
Code starting with the faulting instruction
===========================================
0: c3 retq
1: 8d b6 00 00 00 00 lea 0x0(%rsi),%esi
7: 8d bc 27 00 00 00 00 lea 0x0(%rdi,%riz,1),%edi
e: 8b 1c 24 mov (%rsp),%ebx
11: c3 retq
12: 8d .byte 0x8d
13: b6 00 mov $0x0,%dh
To reproduce:
# build kernel
cd linux
cp config-5.16.0-rc6-00051-gb93acee44e1a .config
make HOSTCC=gcc-9 CC=gcc-9 ARCH=i386 olddefconfig prepare modules_prepare bzImage modules
make HOSTCC=gcc-9 CC=gcc-9 ARCH=i386 INSTALL_MOD_PATH=<mod-install-dir> modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.
---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation
Thanks,
Oliver Sang
7 months
- 1
- 0
[xfs] 7993f1a431: xfstests.xfs.308.fail
by kernel test robot
Greeting,
FYI, we noticed the following commit (built with gcc-9):
commit: 7993f1a431bc5271369d359941485a9340658ac3 ("xfs: only run COW extent recovery when there are no live extents")
url: https://github.com/0day-ci/linux/commits/UPDATE-20220111-073805/trondmy-k...
in testcase: xfstests
version: xfstests-x86_64-972d710-1_20211228
with following parameters:
disk: 4HDD
fs: xfs
test: xfs-reflink-15
ucode: 0x28
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: 8 threads 1 sockets Intel(R) Core(TM) i7-4790 v3 @ 3.60GHz with 6G 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 <oliver.sang(a)intel.com>
2022-01-07 19:55:40 export TEST_DIR=/fs/sda1
2022-01-07 19:55:40 export TEST_DEV=/dev/sda1
2022-01-07 19:55:40 export FSTYP=xfs
2022-01-07 19:55:40 export SCRATCH_MNT=/fs/scratch
2022-01-07 19:55:40 mkdir /fs/scratch -p
2022-01-07 19:55:40 export SCRATCH_DEV=/dev/sda4
2022-01-07 19:55:40 export SCRATCH_LOGDEV=/dev/sda2
2022-01-07 19:55:40 export SCRATCH_XFS_LIST_METADATA_FIELDS=u3.sfdir3.hdr.parent.i4
2022-01-07 19:55:40 export SCRATCH_XFS_LIST_FUZZ_VERBS=random
2022-01-07 19:55:40 export MKFS_OPTIONS=-mreflink=1
2022-01-07 19:55:40 sed "s:^:xfs/:" //lkp/benchmarks/xfstests/tests/xfs-reflink-15
2022-01-07 19:55:40 ./check xfs/307 xfs/308 xfs/309 xfs/312 xfs/313 xfs/315 xfs/316 xfs/319
FSTYP -- xfs (debug)
PLATFORM -- Linux/x86_64 lkp-hsw-d02 5.16.0-rc5-00004-g7993f1a431bc #1 SMP Wed Dec 29 06:35:34 CST 2021
MKFS_OPTIONS -- -f -mreflink=1 /dev/sda4
MOUNT_OPTIONS -- /dev/sda4 /fs/scratch
xfs/307 4s
xfs/308 _check_xfs_filesystem: filesystem on /dev/sda4 is inconsistent (r)
(see /lkp/benchmarks/xfstests/results//xfs/308.full for details)
- output mismatch (see /lkp/benchmarks/xfstests/results//xfs/308.out.bad)
--- tests/xfs/308.out 2021-12-31 10:10:52.000000000 +0000
+++ /lkp/benchmarks/xfstests/results//xfs/308.out.bad 2022-01-07 19:55:49.909107057 +0000
@@ -10,4 +10,6 @@
leftover CoW extent (NR/NR) len NR
Mount filesystem
Look for no more leftover warning in xfs_check
+leftover CoW extent (NR/NR) len NR
Look for no more leftover warning in xfs_repair
+leftover CoW extent (NR/NR) len NR
...
(Run 'diff -u /lkp/benchmarks/xfstests/tests/xfs/308.out /lkp/benchmarks/xfstests/results//xfs/308.out.bad' to see the entire diff)
xfs/309 19s
xfs/312 4s
xfs/313 5s
xfs/315 4s
xfs/316 4s
xfs/319 5s
Ran: xfs/307 xfs/308 xfs/309 xfs/312 xfs/313 xfs/315 xfs/316 xfs/319
Failures: xfs/308
Failed 1 of 8 tests
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
sudo bin/lkp install job.yaml # job file is attached in this email
bin/lkp split-job --compatible job.yaml # generate the yaml file for lkp run
sudo bin/lkp run generated-yaml-file
# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.
---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation
Thanks,
Oliver Sang
7 months
- 1
- 0
[genirq/msi] 495c66aca3: BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c
by kernel test robot
Greeting,
FYI, we noticed the following commit (built with gcc-9):
commit: 495c66aca3da704e063fa373fdbe371e71d3f4ee ("genirq/msi: Convert to new functions")
https://git.kernel.org/cgit/linux/kernel/git/tip/tip.git irq/msi
in testcase: xfstests
version: xfstests-x86_64-972d710-1_20211221
with following parameters:
disk: 4HDD
fs: xfs
test: xfs-group-14
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 1 sockets 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 <oliver.sang(a)intel.com>
kern :err : [ 126.209306] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:280
kern :err : [ 126.209308] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 5183, name: ls
kern :err : [ 126.209311] preempt_count: 2, expected: 0
kern :warn : [ 126.209312] CPU: 2 PID: 5183 Comm: ls Not tainted 5.16.0-rc5-00091-g495c66aca3da #1
kern :warn : [ 126.209315] Hardware name: Hewlett-Packard HP Pro 3340 MT/17A1, BIOS 8.07 01/24/2013
kern :warn : [ 126.209316] Call Trace:
kern :warn : [ 126.209318] <TASK>
kern :warn : [ 126.209319] dump_stack_lvl (lib/dump_stack.c:107)
kern :warn : [ 126.209323] __might_resched.cold (kernel/sched/core.c:9539 kernel/sched/core.c:9492)
kern :warn : [ 126.209326] ? kasan_unpoison (mm/kasan/shadow.c:108 mm/kasan/shadow.c:142)
kern :warn : [ 126.209330] mutex_lock (kernel/locking/mutex.c:280)
kern :warn : [ 126.209335] ? __mutex_lock_slowpath (kernel/locking/mutex.c:279)
kern :warn : [ 126.209339] ? _raw_spin_lock_irqsave (arch/x86/include/asm/atomic.h:202 include/linux/atomic/atomic-instrumented.h:513 include/asm-generic/qspinlock.h:82 include/linux/spinlock.h:185 include/linux/spinlock_api_smp.h:111 kernel/locking/spinlock.c:162)
kern :warn : [ 126.209342] ? _raw_read_unlock_irqrestore (kernel/locking/spinlock.c:161)
kern :warn : [ 126.209344] msi_get_virq (kernel/irq/msi.c:332)
kern :warn : [ 126.209349] pci_irq_vector (drivers/pci/msi/msi.c:1085 drivers/pci/msi/msi.c:1077)
kern :warn : [ 126.209354] rtl8169_netpoll (drivers/net/ethernet/realtek/r8169_main.c:4722)
kern :warn : [ 126.209358] netpoll_poll_dev (net/core/netpoll.c:166 net/core/netpoll.c:195)
kern :warn : [ 126.209363] netpoll_send_skb (net/core/netpoll.c:350 net/core/netpoll.c:376)
kern :warn : [ 126.209367] write_msg (drivers/net/netconsole.c:862 drivers/net/netconsole.c:836) netconsole
kern :warn : [ 126.209372] console_unlock (kernel/printk/printk.c:1929 kernel/printk/printk.c:2711)
kern :warn : [ 126.209375] ? do_syslog (kernel/printk/printk.c:2088)
kern :warn : [ 126.209378] ? prb_read_valid (kernel/printk/printk_ringbuffer.c:1928)
kern :warn : [ 126.209382] vprintk_emit (arch/x86/include/asm/preempt.h:85 kernel/printk/printk.c:2246)
kern :warn : [ 126.209384] ? unwind_next_frame (arch/x86/kernel/unwind_orc.c:600)
kern :warn : [ 126.209388] ? xfs_dir2_sf_getdents+0x6e1/0xb40 xfs
kern :warn : [ 126.209502] _printk (kernel/printk/printk.c:2261)
kern :warn : [ 126.209505] ? record_print_text.cold (kernel/printk/printk.c:2261)
kern :warn : [ 126.209508] ? xfs_dir2_sf_getdents+0x6e1/0xb40 xfs
kern :warn : [ 126.209621] ? xfs_dir2_sf_getdents+0x6e1/0xb40 xfs
kern :warn : [ 126.209734] ? xfs_dir2_sf_getdents+0x6e1/0xb40 xfs
kern :warn : [ 126.209851] ? __module_text_address (kernel/module.c:4768)
kern :warn : [ 126.209856] ? xfs_dir2_sf_getdents+0x6e1/0xb40 xfs
kern :warn : [ 126.209968] ? xfs_dir2_sf_getdents+0x6e1/0xb40 xfs
kern :warn : [ 126.210080] show_trace_log_lvl (arch/x86/kernel/dumpstack.c:284)
kern :warn : [ 126.210084] ? xfs_dir2_sf_getdents+0x6e1/0xb40 xfs
kern :warn : [ 126.210197] ? xfs_dir2_sf_getdents+0x728/0xb40 xfs
kern :warn : [ 126.210309] dump_stack_lvl (lib/dump_stack.c:107)
kern :warn : [ 126.210313] xfs_corruption_error (fs/xfs/xfs_error.c:339 fs/xfs/xfs_error.c:324 fs/xfs/xfs_error.c:354) xfs
kern :warn : [ 126.210425] ? xfs_dir2_sf_getdents+0x6e1/0xb40 xfs
kern :warn : [ 126.210536] ? __alloc_pages_slowpath+0x1f80/0x1f80
kern :warn : [ 126.210541] xfs_dir2_sf_getdents+0x728/0xb40 xfs
kern :warn : [ 126.210653] ? xfs_dir2_sf_getdents+0x6e1/0xb40 xfs
kern :warn : [ 126.210764] ? __mod_memcg_lruvec_state (arch/x86/include/asm/atomic.h:95 include/linux/atomic/atomic-instrumented.h:182 mm/memcontrol.c:636 mm/memcontrol.c:632 mm/memcontrol.c:708)
kern :warn : [ 126.210769] xfs_readdir (fs/xfs/xfs_dir2_readdir.c:526) xfs
kern :warn : [ 126.210884] ? do_anonymous_page (mm/memory.c:3800)
kern :warn : [ 126.210889] ? xfs_dir3_get_dtype (fs/xfs/xfs_dir2_readdir.c:508) xfs
kern :warn : [ 126.211001] ? make_kgid (kernel/user_namespace.c:463)
kern :warn : [ 126.211006] ? xfs_vn_getattr (fs/xfs/xfs_iops.c:593) xfs
kern :warn : [ 126.211121] ? security_file_permission (arch/x86/include/asm/atomic64_64.h:22 include/linux/atomic/atomic-long.h:29 include/linux/atomic/atomic-instrumented.h:1184 include/linux/fsnotify.h:62 include/linux/fsnotify.h:99 include/linux/fsnotify.h:124 include/linux/fsnotify.h:103 security/security.c:1511)
kern :warn : [ 126.211125] iterate_dir (fs/readdir.c:65)
kern :warn : [ 126.211130] __x64_sys_getdents64 (fs/readdir.c:370 fs/readdir.c:354 fs/readdir.c:354)
kern :warn : [ 126.211133] ? filldir (fs/readdir.c:354)
kern :warn : [ 126.211135] ? handle_mm_fault (mm/memory.c:4784)
kern :warn : [ 126.211138] ? __ia32_sys_getdents64 (fs/readdir.c:312)
kern :warn : [ 126.211141] ? do_user_addr_fault (arch/x86/mm/fault.c:1423)
kern :warn : [ 126.211144] ? exit_to_user_mode_prepare (arch/x86/include/asm/entry-common.h:58 kernel/entry/common.c:209)
kern :warn : [ 126.211148] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
kern :warn : [ 126.211153] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:113)
kern :warn : [ 126.211156] RIP: 0033:0x7f82c632edd7
kern :warn : [ 126.211159] Code: 00 00 48 83 c4 08 5b 5d c3 66 2e 0f 1f 84 00 00 00 00 00 48 8b 47 20 c3 66 2e 0f 1f 84 00 00 00 00 00 90 b8 d9 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 01 c3 48 8b 15 89 90 0f 00 f7 d8 64 89 02 48
All code
========
0: 00 00 add %al,(%rax)
2: 48 83 c4 08 add $0x8,%rsp
6: 5b pop %rbx
7: 5d pop %rbp
8: c3 retq
9: 66 2e 0f 1f 84 00 00 nopw %cs:0x0(%rax,%rax,1)
10: 00 00 00
13: 48 8b 47 20 mov 0x20(%rdi),%rax
17: c3 retq
18: 66 2e 0f 1f 84 00 00 nopw %cs:0x0(%rax,%rax,1)
1f: 00 00 00
22: 90 nop
23: b8 d9 00 00 00 mov $0xd9,%eax
28: 0f 05 syscall
2a:* 48 3d 00 f0 ff ff cmp $0xfffffffffffff000,%rax <-- trapping instruction
30: 77 01 ja 0x33
32: c3 retq
33: 48 8b 15 89 90 0f 00 mov 0xf9089(%rip),%rdx # 0xf90c3
3a: f7 d8 neg %eax
3c: 64 89 02 mov %eax,%fs:(%rdx)
3f: 48 rex.W
Code starting with the faulting instruction
===========================================
0: 48 3d 00 f0 ff ff cmp $0xfffffffffffff000,%rax
6: 77 01 ja 0x9
8: c3 retq
9: 48 8b 15 89 90 0f 00 mov 0xf9089(%rip),%rdx # 0xf9099
10: f7 d8 neg %eax
12: 64 89 02 mov %eax,%fs:(%rdx)
15: 48 rex.W
kern :warn : [ 126.211162] RSP: 002b:00007ffd9859b988 EFLAGS: 00000246 ORIG_RAX: 00000000000000d9
kern :warn : [ 126.211166] RAX: ffffffffffffffda RBX: 0000562c94087960 RCX: 00007f82c632edd7
kern :warn : [ 126.211168] RDX: 0000000000008000 RSI: 0000562c94087990 RDI: 0000000000000003
kern :warn : [ 126.211170] RBP: 0000562c94087990 R08: 0000000000000003 R09: 0000000000000078
kern :warn : [ 126.211172] R10: fffffffffffff5e9 R11: 0000000000000246 R12: fffffffffffffe98
kern :warn : [ 126.211174] R13: 0000000000000000 R14: 0000562c94087930 R15: 0000562c921bf8c1
kern :warn : [ 126.211176] </TASK>
kern :warn : [ 126.606948] ? __alloc_pages_slowpath+0x1f80/0x1f80
kern :warn : [ 126.613666] xfs_dir2_sf_getdents+0x728/0xb40 xfs
kern :warn : [ 126.620046] ? xfs_dir2_sf_getdents+0x6e1/0xb40 xfs
kern :warn : [ 126.626623] ? __mod_memcg_lruvec_state (arch/x86/include/asm/atomic.h:95 include/linux/atomic/atomic-instrumented.h:182 mm/memcontrol.c:636 mm/memcontrol.c:632 mm/memcontrol.c:708)
kern :warn : [ 126.632281] xfs_readdir (fs/xfs/xfs_dir2_readdir.c:526) xfs
kern :warn : [ 126.637258] ? do_anonymous_page (mm/memory.c:3800)
kern :warn : [ 126.642395] ? xfs_dir3_get_dtype (fs/xfs/xfs_dir2_readdir.c:508) xfs
kern :warn : [ 126.647995] ? make_kgid (kernel/user_namespace.c:463)
kern :warn : [ 126.652155] ? xfs_vn_getattr (fs/xfs/xfs_iops.c:593) xfs
kern :warn : [ 126.657573] ? security_file_permission (arch/x86/include/asm/atomic64_64.h:22 include/linux/atomic/atomic-long.h:29 include/linux/atomic/atomic-instrumented.h:1184 include/linux/fsnotify.h:62 include/linux/fsnotify.h:99 include/linux/fsnotify.h:124 include/linux/fsnotify.h:103 security/security.c:1511)
kern :warn : [ 126.663239] iterate_dir (fs/readdir.c:65)
kern :warn : [ 126.667598] __x64_sys_getdents64 (fs/readdir.c:370 fs/readdir.c:354 fs/readdir.c:354)
kern :warn : [ 126.672756] ? filldir (fs/readdir.c:354)
kern :warn : [ 126.676940] ? handle_mm_fault (mm/memory.c:4784)
kern :warn : [ 126.681806] ? __ia32_sys_getdents64 (fs/readdir.c:312)
kern :warn : [ 126.687177] ? do_user_addr_fault (arch/x86/mm/fault.c:1423)
kern :warn : [ 126.692697] ? exit_to_user_mode_prepare (arch/x86/include/asm/entry-common.h:58 kernel/entry/common.c:209)
kern :warn : [ 126.698444] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
kern :warn : [ 126.702804] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:113)
kern :warn : [ 126.708653] RIP: 0033:0x7f82c632edd7
kern :warn : [ 126.713002] Code: 00 00 48 83 c4 08 5b 5d c3 66 2e 0f 1f 84 00 00 00 00 00 48 8b 47 20 c3 66 2e 0f 1f 84 00 00 00 00 00 90 b8 d9 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 01 c3 48 8b 15 89 90 0f 00 f7 d8 64 89 02 48
All code
========
0: 00 00 add %al,(%rax)
2: 48 83 c4 08 add $0x8,%rsp
6: 5b pop %rbx
7: 5d pop %rbp
8: c3 retq
9: 66 2e 0f 1f 84 00 00 nopw %cs:0x0(%rax,%rax,1)
10: 00 00 00
13: 48 8b 47 20 mov 0x20(%rdi),%rax
17: c3 retq
18: 66 2e 0f 1f 84 00 00 nopw %cs:0x0(%rax,%rax,1)
1f: 00 00 00
22: 90 nop
23: b8 d9 00 00 00 mov $0xd9,%eax
28: 0f 05 syscall
2a:* 48 3d 00 f0 ff ff cmp $0xfffffffffffff000,%rax <-- trapping instruction
30: 77 01 ja 0x33
32: c3 retq
33: 48 8b 15 89 90 0f 00 mov 0xf9089(%rip),%rdx # 0xf90c3
3a: f7 d8 neg %eax
3c: 64 89 02 mov %eax,%fs:(%rdx)
3f: 48 rex.W
Code starting with the faulting instruction
===========================================
0: 48 3d 00 f0 ff ff cmp $0xfffffffffffff000,%rax
6: 77 01 ja 0x9
8: c3 retq
9: 48 8b 15 89 90 0f 00 mov 0xf9089(%rip),%rdx # 0xf9099
10: f7 d8 neg %eax
12: 64 89 02 mov %eax,%fs:(%rdx)
15: 48 rex.W
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
sudo bin/lkp install job.yaml # job file is attached in this email
bin/lkp split-job --compatible job.yaml # generate the yaml file for lkp run
sudo bin/lkp run generated-yaml-file
# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.
---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation
Thanks,
Oliver Sang
7 months
- 3
- 3