[<prev] [next>] [<thread-prev] [day] [month] [year] [list]
Message-ID: <20220421145932.GC7803@xsang-OptiPlex-9020>
Date: Thu, 21 Apr 2022 22:59:32 +0800
From: kernel test robot <oliver.sang@...el.com>
To: Zhang Yi <yi.zhang@...wei.com>
Cc: 0day robot <lkp@...el.com>, LKML <linux-kernel@...r.kernel.org>,
lkp@...ts.01.org, linux-ext4@...r.kernel.org, tytso@....edu,
adilger.kernel@...ger.ca, jack@...e.cz, yi.zhang@...wei.com,
yukuai3@...wei.com, yebin10@...wei.com
Subject: [ext4] bfe57bd7e1:
BUG:sleeping_function_called_from_invalid_context_at_fs/buffer.c
Greeting,
FYI, we noticed the following commit (built with gcc-11):
commit: bfe57bd7e18960dfb34534a326fa10155d10f92a ("[RFC PATCH v3] ext4: convert symlink external data block mapping to bdev")
url: https://github.com/intel-lab-lkp/linux/commits/Zhang-Yi/ext4-convert-symlink-external-data-block-mapping-to-bdev/20220418-143737
base: https://git.kernel.org/cgit/linux/kernel/git/tytso/ext4.git dev
patch link: https://lore.kernel.org/linux-ext4/20220418063735.2067766-1-yi.zhang@huawei.com
in testcase: xfstests
version: xfstests-x86_64-46e1b83-1_20220414
with following parameters:
disk: 4HDD
fs: ext4
test: generic-group-32
ucode: 0xec
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@...el.com>
[ 259.165108][ T4690] EXT4-fs (sda4): INFO: recovery required on readonly filesystem
[ 259.172699][ T4690] EXT4-fs (sda4): write access will be enabled during recovery
[ 259.262233][ T4690] EXT4-fs (sda4): recovery complete
[ 259.287473][ T4690] EXT4-fs (sda4): mounted filesystem with ordered data mode. Quota mode: none.
[ 259.374823][ T4702] EXT4-fs (sda4): mounted filesystem with ordered data mode. Quota mode: none.
[ 260.108492][ T4726] EXT4-fs (sda4): mounted filesystem with ordered data mode. Quota mode: none.
[ 260.177189][ T314] generic/646 24s
[ 260.177198][ T314]
[ 260.224149][ T1550] run fstests generic/647 at 2022-04-20 20:24:33
[ 260.585202][ T4905] EXT4-fs (sda1): mounted filesystem with ordered data mode. Quota mode: none.
[ 262.085184][ T5110] EXT4-fs (sda1): mounted filesystem with ordered data mode. Quota mode: none.
[ 262.140559][ T314] generic/647 1s
[ 262.140568][ T314]
[ 262.188396][ T1550] run fstests generic/648 at 2022-04-20 20:24:35
[ 273.534811][ T5381] EXT4-fs (sda4): mounted filesystem with ordered data mode. Quota mode: none.
[ 273.752566][ T314] generic/648 [not run] Reflink not supported by scratch filesystem type: ext4
[ 273.752575][ T314]
[ 273.806605][ T1550] run fstests generic/649 at 2022-04-20 20:24:46
[ 274.250167][ T314] generic/649 [not run] Reflink not supported by test filesystem type: ext4
[ 274.250176][ T314]
[ 274.304279][ T1550] run fstests generic/650 at 2022-04-20 20:24:47
[ 274.665973][ T5815] smpboot: CPU 1 is now offline
[ 275.282175][ T5815] smpboot: CPU 0 is now offline
[ 275.819609][ T5815] smpboot: CPU 3 is now offline
[ 278.338825][ T5815] x86: Booting SMP configuration:
[ 278.343724][ T5815] smpboot: Booting Node 0 Processor 1 APIC 0x2
[ 278.350221][ T0] masked ExtINT on CPU#1
[ 278.354345][ T0] CPU0: Thermal monitoring enabled (TM1)
[ 278.895948][ T5815] smpboot: Booting Node 0 Processor 3 APIC 0x6
[ 278.902236][ T0] masked ExtINT on CPU#3
[ 279.996061][ T5815] smpboot: CPU 2 is now offline
[ 281.088956][ T5815] smpboot: CPU 1 is now offline
[ 282.100211][ T5815] x86: Booting SMP configuration:
[ 282.105112][ T5815] smpboot: Booting Node 0 Processor 1 APIC 0x2
[ 282.111607][ T0] masked ExtINT on CPU#1
[ 282.214467][ T5820] BUG: sleeping function called from invalid context at fs/buffer.c:1332
[ 282.222775][ T5820] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 5820, name: fsstress
[ 282.231492][ T5820] preempt_count: 1, expected: 0
[ 282.236206][ T5820] CPU: 1 PID: 5820 Comm: fsstress Tainted: G I 5.17.0-rc5-00038-gbfe57bd7e189 #1
[ 282.246472][ T5820] Hardware name: Dell Inc. OptiPlex 7040/0Y7WYT, BIOS 1.1.1 10/07/2015
[ 282.254563][ T5820] Call Trace:
[ 282.257703][ T5820] <TASK>
[ 282.260498][ T5820] dump_stack_lvl (lib/dump_stack.c:107 (discriminator 1))
[ 282.264859][ T5820] __might_resched.cold (kernel/sched/core.c:9586)
[ 282.269914][ T5820] __getblk_gfp (fs/buffer.c:1332)
[ 282.274188][ T5820] ext4_getblk (include/linux/buffer_head.h:327 fs/ext4/inode.c:855)
[ 282.278458][ T5820] ? current_time (fs/inode.c:2400)
[ 282.282903][ T5820] ? ext4_iomap_overwrite_begin (fs/ext4/inode.c:837)
[ 282.288478][ T5820] ext4_get_link (fs/ext4/symlink.c:79)
[ 282.292924][ T5820] pick_link (fs/namei.c:1815)
[ 282.297024][ T5820] ? __d_lookup_rcu (fs/dcache.c:226 fs/dcache.c:278 fs/dcache.c:2368)
[ 282.301730][ T5820] ? ext4_encrypted_get_link (fs/ext4/symlink.c:75)
[ 282.307231][ T5820] step_into (fs/namei.c:1876)
[ 282.311328][ T5820] ? pick_link (fs/namei.c:1848)
[ 282.315600][ T5820] ? generic_permission (fs/namei.c:296 fs/namei.c:350 fs/namei.c:403)
[ 282.320654][ T5820] ? try_to_unlazy_next (fs/namei.c:1619)
[ 282.325708][ T5820] ? inode_permission (fs/namei.c:525 fs/namei.c:499)
[ 282.330500][ T5820] open_last_lookups (fs/namei.c:3423)
[ 282.335381][ T5820] ? lookup_open+0x1740/0x1740
[ 282.340610][ T5820] path_openat (fs/namei.c:3606 (discriminator 1))
[ 282.344885][ T5820] ? open_last_lookups (fs/namei.c:3591)
[ 282.350024][ T5820] ? kasan_save_stack (mm/kasan/common.c:40)
[ 282.354731][ T5820] ? kasan_save_stack (mm/kasan/common.c:39)
[ 282.359449][ T5820] ? __kasan_record_aux_stack (mm/kasan/generic.c:348)
[ 282.364851][ T5820] ? __call_rcu (kernel/rcu/tree.c:3027 (discriminator 3))
[ 282.369122][ T5820] ? task_work_run (kernel/task_work.c:166 (discriminator 1))
[ 282.373655][ T5820] ? exit_to_user_mode_loop (include/linux/tracehook.h:188 kernel/entry/common.c:175)
[ 282.379054][ T5820] do_filp_open (fs/namei.c:3636)
[ 282.383415][ T5820] ? may_open_dev (fs/namei.c:3630)
[ 282.387946][ T5820] ? _raw_spin_lock (arch/x86/include/asm/atomic.h:202 include/linux/atomic/atomic-instrumented.h:543 include/asm-generic/qspinlock.h:82 include/linux/spinlock.h:185 include/linux/spinlock_api_smp.h:134 kernel/locking/spinlock.c:154)
[ 282.392565][ T5820] ? _raw_write_lock_irq (kernel/locking/spinlock.c:153)
[ 282.397705][ T5820] ? __check_object_size (mm/memremap.c:128)
[ 282.403455][ T5820] ? alloc_fd (arch/x86/include/asm/bitops.h:214 include/asm-generic/bitops/instrumented-non-atomic.h:135 fs/file.c:240 fs/file.c:513)
[ 282.407642][ T5820] do_sys_openat2 (fs/open.c:1214)
[ 282.412177][ T5820] ? build_open_flags (fs/open.c:1200)
[ 282.417057][ T5820] ? __fput (include/linux/percpu_counter.h:189 fs/file_table.c:58 fs/file_table.c:335)
[ 282.421067][ T5820] __x64_sys_openat (fs/open.c:1241)
[ 282.425775][ T5820] ? __ia32_compat_sys_open (fs/open.c:1241)
[ 282.431175][ T5820] ? exit_to_user_mode_loop (include/linux/sched.h:2223 include/linux/tracehook.h:200 kernel/entry/common.c:175)
[ 282.436497][ T5820] ? exit_to_user_mode_prepare (kernel/entry/common.c:207)
[ 282.442082][ T5820] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
[ 282.446359][ T5820] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:113)
[ 282.452119][ T5820] RIP: 0033:0x7f418eef21ae
[ 282.456396][ T5820] Code: 25 00 00 41 00 3d 00 00 41 00 74 48 48 8d 05 59 65 0d 00 8b 00 85 c0 75 69 89 f2 b8 01 01 00 00 48 89 fe bf 9c ff ff ff 0f 05 <48> 3d 00 f0 ff ff 0f 87 a6 00 00 00 48 8b 4c 24 28 64 48 33 0c 25
All code
========
0: 25 00 00 41 00 and $0x410000,%eax
5: 3d 00 00 41 00 cmp $0x410000,%eax
a: 74 48 je 0x54
c: 48 8d 05 59 65 0d 00 lea 0xd6559(%rip),%rax # 0xd656c
13: 8b 00 mov (%rax),%eax
15: 85 c0 test %eax,%eax
17: 75 69 jne 0x82
19: 89 f2 mov %esi,%edx
1b: b8 01 01 00 00 mov $0x101,%eax
20: 48 89 fe mov %rdi,%rsi
23: bf 9c ff ff ff mov $0xffffff9c,%edi
28: 0f 05 syscall
2a:* 48 3d 00 f0 ff ff cmp $0xfffffffffffff000,%rax <-- trapping instruction
30: 0f 87 a6 00 00 00 ja 0xdc
36: 48 8b 4c 24 28 mov 0x28(%rsp),%rcx
3b: 64 fs
3c: 48 rex.W
3d: 33 .byte 0x33
3e: 0c 25 or $0x25,%al
Code starting with the faulting instruction
===========================================
0: 48 3d 00 f0 ff ff cmp $0xfffffffffffff000,%rax
6: 0f 87 a6 00 00 00 ja 0xb2
c: 48 8b 4c 24 28 mov 0x28(%rsp),%rcx
11: 64 fs
12: 48 rex.W
13: 33 .byte 0x33
14: 0c 25 or $0x25,%al
[ 282.475881][ T5820] RSP: 002b:00007ffe802301e0 EFLAGS: 00000246 ORIG_RAX: 0000000000000101
[ 282.484151][ T5820] RAX: ffffffffffffffda RBX: 00007ffe802303b0 RCX: 00007f418eef21ae
[ 282.491982][ T5820] RDX: 0000000000000002 RSI: 000055b0f61e64f0 RDI: 00000000ffffff9c
[ 282.499814][ T5820] RBP: 00007ffe802303b0 R08: 00007f418efc3c40 R09: 00007ffe8022ffc6
[ 282.507646][ T5820] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000002
[ 282.515479][ T5820] R13: 28f5c28f5c28f5c3 R14: 00007ffe80230430 R15: 000055b0f53e6d00
[ 282.523312][ T5820] </TASK>
[ 282.642902][ T5815] smpboot: Booting Node 0 Processor 2 APIC 0x4
[ 282.649228][ T0] masked ExtINT on CPU#2
[ 283.693053][ T5815] smpboot: CPU 2 is now offline
[ 285.223216][ T5815] smpboot: CPU 1 is now offline
[ 285.732314][ T5815] smpboot: Booting Node 0 Processor 2 APIC 0x4
[ 285.738606][ T0] masked ExtINT on CPU#2
[ 286.276104][ T5815] smpboot: CPU 2 is now offline
[ 287.287678][ T5815] smpboot: Booting Node 0 Processor 0 APIC 0x0
[ 287.293921][ T0] masked ExtINT on CPU#0
[ 287.844906][ T5815] smpboot: Booting Node 0 Processor 2 APIC 0x4
[ 287.851219][ T0] masked ExtINT on CPU#2
[ 288.890682][ T5815] smpboot: CPU 2 is now offline
[ 290.921923][ T5815] x86: Booting SMP configuration:
[ 290.926833][ T5815] smpboot: Booting Node 0 Processor 1 APIC 0x2
[ 290.933281][ T0] masked ExtINT on CPU#1
[ 292.469942][ T5815] smpboot: Booting Node 0 Processor 2 APIC 0x4
[ 292.476227][ T0] masked ExtINT on CPU#2
[ 293.069136][ T5815] smpboot: CPU 3 is now offline
[ 294.118103][ T5815] smpboot: CPU 1 is now offline
[ 295.140956][ T5815] x86: Booting SMP configuration:
[ 295.145864][ T5815] smpboot: Booting Node 0 Processor 1 APIC 0x2
[ 295.152276][ T0] masked ExtINT on CPU#1
[ 296.190768][ T5815] smpboot: CPU 1 is now offline
[ 299.225915][ T5815] smpboot: Booting Node 0 Processor 3 APIC 0x6
[ 299.232198][ T0] masked ExtINT on CPU#3
[ 300.274255][ T5815] smpboot: CPU 3 is now offline
[ 300.851916][ T5815] smpboot: CPU 0 is now offline
[ 301.863364][ T5815] smpboot: Booting Node 0 Processor 0 APIC 0x0
[ 301.869609][ T0] masked ExtINT on CPU#0
[ 302.407915][ T5815] x86: Booting SMP configuration:
[ 302.412816][ T5815] smpboot: Booting Node 0 Processor 1 APIC 0x2
[ 302.419128][ T0] masked ExtINT on CPU#1
[ 302.960837][ T5815] smpboot: CPU 1 is now offline
[ 303.499912][ T5815] smpboot: Booting Node 0 Processor 3 APIC 0x6
[ 303.506220][ T0] masked ExtINT on CPU#3
[ 304.047924][ T5815] smpboot: CPU 3 is now offline
[ 304.585186][ T5815] smpboot: CPU 0 is now offline
[ 305.094921][ T5815] smpboot: Booting Node 0 Processor 3 APIC 0x6
[ 305.101210][ T0] masked ExtINT on CPU#3
[ 306.140631][ T5815] x86: Booting SMP configuration:
[ 306.145563][ T5815] smpboot: Booting Node 0 Processor 1 APIC 0x2
[ 306.151870][ T0] masked ExtINT on CPU#1
[ 306.748147][ T5815] smpboot: CPU 2 is now offline
[ 307.342924][ T5815] smpboot: CPU 1 is now offline
[ 307.852098][ T5815] smpboot: Booting Node 0 Processor 0 APIC 0x0
[ 307.858343][ T0] masked ExtINT on CPU#0
[ 308.954917][ T5815] smpboot: CPU 3 is now offline
[ 309.464994][ T5815] x86: Booting SMP configuration:
[ 309.469894][ T5815] smpboot: Booting Node 0 Processor 1 APIC 0x2
[ 309.476590][ T0] masked ExtINT on CPU#1
[ 311.009963][ T5815] smpboot: Booting Node 0 Processor 3 APIC 0x6
[ 311.016284][ T0] masked ExtINT on CPU#3
[ 312.121176][ T5815] smpboot: CPU 0 is now offline
[ 312.655850][ T5815] smpboot: Booting Node 0 Processor 2 APIC 0x4
[ 312.662144][ T0] masked ExtINT on CPU#2
[ 313.712887][ T5815] smpboot: CPU 3 is now offline
[ 314.246039][ T5815] smpboot: CPU 2 is now offline
[ 314.755223][ T5815] smpboot: Booting Node 0 Processor 2 APIC 0x4
[ 314.761514][ T0] masked ExtINT on CPU#2
[ 316.802935][ T5815] smpboot: Booting Node 0 Processor 3 APIC 0x6
[ 316.809266][ T0] masked ExtINT on CPU#3
[ 317.847960][ T5815] smpboot: Booting Node 0 Processor 0 APIC 0x0
[ 317.854196][ T0] masked ExtINT on CPU#0
[ 319.404776][ T5815] smpboot: CPU 3 is now offline
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.
--
0-DAY CI Kernel Test Service
https://01.org/lkp
View attachment "config-5.17.0-rc5-00038-gbfe57bd7e189" of type "text/plain" (164819 bytes)
View attachment "job-script" of type "text/plain" (5779 bytes)
Download attachment "dmesg.xz" of type "application/x-xz" (33948 bytes)
View attachment "xfstests" of type "text/plain" (2309 bytes)
View attachment "job.yaml" of type "text/plain" (4752 bytes)
View attachment "reproduce" of type "text/plain" (941 bytes)
Powered by blists - more mailing lists