* [paulmckrcu:dev] [mm/filemap] 199735cdc2: BUG:sleeping_function_called_from_invalid_context_at_mm/filemap.c
@ 2024-08-25 8:46 kernel test robot
2024-08-26 16:17 ` Paul E. McKenney
0 siblings, 1 reply; 2+ messages in thread
From: kernel test robot @ 2024-08-25 8:46 UTC (permalink / raw)
To: Paul E. McKenney
Cc: oe-lkp, lkp, Dave Jones, Matthew Wilcox (Oracle), Andrew Morton,
linux-fsdevel, oliver.sang
Hello,
kernel test robot noticed "BUG:sleeping_function_called_from_invalid_context_at_mm/filemap.c" on:
commit: 199735cdc2b09f27ee095b39d1a67ea6888d2dc8 ("mm/filemap: Add cond_resched() to find_get_entry() retry loop")
https://github.com/paulmckrcu/linux dev
in testcase: boot
compiler: gcc-12
test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G
(please refer to attached dmesg/kmsg for entire log/backtrace)
+-------------------------------------------------------------------+------------+------------+
| | cf6115748c | 199735cdc2 |
+-------------------------------------------------------------------+------------+------------+
| boot_successes | 18 | 0 |
| boot_failures | 0 | 18 |
| BUG:sleeping_function_called_from_invalid_context_at_mm/filemap.c | 0 | 18 |
+-------------------------------------------------------------------+------------+------------+
If you fix the issue in a separate patch/commit (i.e. not just a new version of
the same patch/commit), kindly add following tags
| Reported-by: kernel test robot <oliver.sang@intel.com>
| Closes: https://lore.kernel.org/oe-lkp/202408251605.df83b338-lkp@intel.com
[ 8.441495][ T10] BUG: sleeping function called from invalid context at mm/filemap.c:1989
[ 8.442309][ T10] in_atomic(): 0, irqs_disabled(): 0, non_block: 0, pid: 10, name: kworker/u4:0
[ 8.443047][ T10] preempt_count: 0, expected: 0
[ 8.443427][ T10] RCU nest depth: 1, expected: 0
[ 8.443813][ T10] 4 locks held by kworker/u4:0/10:
[ 8.444214][ T10] #0: ffff888100a76d50 ((wq_completion)async){+.+.}-{0:0}, at: process_one_work (kernel/workqueue.c:3206)
[ 8.445025][ T10] #1: ffff888101e7be48 ((work_completion)(&entry->work)){+.+.}-{0:0}, at: process_one_work (kernel/workqueue.c:3207)
[ 8.446045][ T10] #2: ffff888100e71430 (sb_writers#2){.+.+}-{0:0}, at: do_unlinkat (fs/namei.c:4470)
[ 8.446763][ T10] #3: ffffffff833f0d00 (rcu_read_lock){....}-{1:2}, at: find_lock_entries (include/linux/rcupdate.h:337 include/linux/rcupdate.h:849 mm/filemap.c:2091)
[ 8.447523][ T10] CPU: 0 UID: 0 PID: 10 Comm: kworker/u4:0 Not tainted 6.11.0-rc1-00121-g199735cdc2b0 #1 ada3dbbd8db49aa7f6dc3bbe9d2d7b34f1d68c93
[ 8.448151][ T10] Workqueue: async async_run_entry_fn
[ 8.448151][ T10] Call Trace:
[ 8.448151][ T10] <TASK>
[ 8.448151][ T10] dump_stack_lvl (lib/dump_stack.c:122 (discriminator 1))
[ 8.448151][ T10] __might_resched (kernel/sched/core.c:8439)
[ 8.448151][ T10] find_lock_entries (include/linux/sched.h:2007 mm/filemap.c:1989 mm/filemap.c:2092)
[ 8.448151][ T10] ? find_lock_entries (include/linux/rcupdate.h:337 include/linux/rcupdate.h:849 mm/filemap.c:2091)
[ 8.448151][ T10] truncate_inode_pages_range (mm/truncate.c:338 (discriminator 1))
[ 8.448151][ T10] ? save_trace (kernel/locking/lockdep.c:585)
[ 8.448151][ T10] ? add_lock_to_list (include/linux/rculist.h:79 include/linux/rculist.h:128 kernel/locking/lockdep.c:1431)
[ 8.448151][ T10] ? check_prev_add (kernel/locking/lockdep.c:3213)
[ 8.448151][ T10] ? validate_chain (kernel/locking/lockdep.c:156 kernel/locking/lockdep.c:185 kernel/locking/lockdep.c:3872)
[ 8.448151][ T10] ? 0xffffffff81000000
[ 8.448151][ T10] ? truncate_inode_pages_final (include/linux/spinlock.h:401 mm/truncate.c:455)
[ 8.448151][ T10] ? kvm_sched_clock_read (arch/x86/kernel/kvmclock.c:91)
[ 8.448151][ T10] ? mark_held_locks (kernel/locking/lockdep.c:4273)
[ 8.448151][ T10] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4422)
[ 8.448151][ T10] evict (fs/inode.c:672)
[ 8.448151][ T10] do_unlinkat (fs/namei.c:4493)
[ 8.448151][ T10] clean_path (init/initramfs.c:341)
[ 8.448151][ T10] do_symlink (init/initramfs.c:428)
[ 8.448151][ T10] ? do_collect (init/initramfs.c:267)
[ 8.448151][ T10] flush_buffer (init/initramfs.c:452 init/initramfs.c:464)
[ 8.448151][ T10] ? bunzip2 (lib/decompress_inflate.c:37)
[ 8.448151][ T10] ? do_name (init/initramfs.c:458)
[ 8.448151][ T10] __gunzip+0x2b0/0x380
[ 8.448151][ T10] unpack_to_rootfs (init/initramfs.c:522)
[ 8.448151][ T10] ? initrd_load (init/initramfs.c:59)
[ 8.448151][ T10] do_populate_rootfs (init/initramfs.c:706 (discriminator 1))
[ 8.448151][ T10] async_run_entry_fn (kernel/async.c:136)
[ 8.448151][ T10] process_one_work (kernel/workqueue.c:3236)
[ 8.448151][ T10] ? process_one_work (kernel/workqueue.c:3207)
[ 8.448151][ T10] ? worker_thread (kernel/workqueue.c:3349)
[ 8.448151][ T10] worker_thread (kernel/workqueue.c:3306 kernel/workqueue.c:3390)
[ 8.448151][ T10] ? rescuer_thread (kernel/workqueue.c:3339)
[ 8.448151][ T10] kthread (kernel/kthread.c:389)
[ 8.448151][ T10] ? kthread_park (kernel/kthread.c:342)
[ 8.448151][ T10] ret_from_fork (arch/x86/kernel/process.c:153)
[ 8.448151][ T10] ? kthread_park (kernel/kthread.c:342)
[ 8.448151][ T10] ret_from_fork_asm (arch/x86/entry/entry_64.S:254)
[ 8.448151][ T10] </TASK>
[ 8.949844][ T10] Freeing initrd memory: 198316K
The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20240825/202408251605.df83b338-lkp@intel.com
--
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki
^ permalink raw reply [flat|nested] 2+ messages in thread
* Re: [paulmckrcu:dev] [mm/filemap] 199735cdc2: BUG:sleeping_function_called_from_invalid_context_at_mm/filemap.c
2024-08-25 8:46 [paulmckrcu:dev] [mm/filemap] 199735cdc2: BUG:sleeping_function_called_from_invalid_context_at_mm/filemap.c kernel test robot
@ 2024-08-26 16:17 ` Paul E. McKenney
0 siblings, 0 replies; 2+ messages in thread
From: Paul E. McKenney @ 2024-08-26 16:17 UTC (permalink / raw)
To: kernel test robot
Cc: oe-lkp, lkp, Dave Jones, Matthew Wilcox (Oracle), Andrew Morton,
linux-fsdevel
On Sun, Aug 25, 2024 at 04:46:52PM +0800, kernel test robot wrote:
>
>
> Hello,
>
> kernel test robot noticed "BUG:sleeping_function_called_from_invalid_context_at_mm/filemap.c" on:
>
> commit: 199735cdc2b09f27ee095b39d1a67ea6888d2dc8 ("mm/filemap: Add cond_resched() to find_get_entry() retry loop")
> https://github.com/paulmckrcu/linux dev
>
> in testcase: boot
>
> compiler: gcc-12
> test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G
>
> (please refer to attached dmesg/kmsg for entire log/backtrace)
It is a good thing I have a brown paper bag at hand. :-(
Good catch, and thank you for your testing efforts. In the short term,
I will be dropping this patch.
Thanx, Paul
> +-------------------------------------------------------------------+------------+------------+
> | | cf6115748c | 199735cdc2 |
> +-------------------------------------------------------------------+------------+------------+
> | boot_successes | 18 | 0 |
> | boot_failures | 0 | 18 |
> | BUG:sleeping_function_called_from_invalid_context_at_mm/filemap.c | 0 | 18 |
> +-------------------------------------------------------------------+------------+------------+
>
>
> If you fix the issue in a separate patch/commit (i.e. not just a new version of
> the same patch/commit), kindly add following tags
> | Reported-by: kernel test robot <oliver.sang@intel.com>
> | Closes: https://lore.kernel.org/oe-lkp/202408251605.df83b338-lkp@intel.com
>
>
> [ 8.441495][ T10] BUG: sleeping function called from invalid context at mm/filemap.c:1989
> [ 8.442309][ T10] in_atomic(): 0, irqs_disabled(): 0, non_block: 0, pid: 10, name: kworker/u4:0
> [ 8.443047][ T10] preempt_count: 0, expected: 0
> [ 8.443427][ T10] RCU nest depth: 1, expected: 0
> [ 8.443813][ T10] 4 locks held by kworker/u4:0/10:
> [ 8.444214][ T10] #0: ffff888100a76d50 ((wq_completion)async){+.+.}-{0:0}, at: process_one_work (kernel/workqueue.c:3206)
> [ 8.445025][ T10] #1: ffff888101e7be48 ((work_completion)(&entry->work)){+.+.}-{0:0}, at: process_one_work (kernel/workqueue.c:3207)
> [ 8.446045][ T10] #2: ffff888100e71430 (sb_writers#2){.+.+}-{0:0}, at: do_unlinkat (fs/namei.c:4470)
> [ 8.446763][ T10] #3: ffffffff833f0d00 (rcu_read_lock){....}-{1:2}, at: find_lock_entries (include/linux/rcupdate.h:337 include/linux/rcupdate.h:849 mm/filemap.c:2091)
> [ 8.447523][ T10] CPU: 0 UID: 0 PID: 10 Comm: kworker/u4:0 Not tainted 6.11.0-rc1-00121-g199735cdc2b0 #1 ada3dbbd8db49aa7f6dc3bbe9d2d7b34f1d68c93
> [ 8.448151][ T10] Workqueue: async async_run_entry_fn
> [ 8.448151][ T10] Call Trace:
> [ 8.448151][ T10] <TASK>
> [ 8.448151][ T10] dump_stack_lvl (lib/dump_stack.c:122 (discriminator 1))
> [ 8.448151][ T10] __might_resched (kernel/sched/core.c:8439)
> [ 8.448151][ T10] find_lock_entries (include/linux/sched.h:2007 mm/filemap.c:1989 mm/filemap.c:2092)
> [ 8.448151][ T10] ? find_lock_entries (include/linux/rcupdate.h:337 include/linux/rcupdate.h:849 mm/filemap.c:2091)
> [ 8.448151][ T10] truncate_inode_pages_range (mm/truncate.c:338 (discriminator 1))
> [ 8.448151][ T10] ? save_trace (kernel/locking/lockdep.c:585)
> [ 8.448151][ T10] ? add_lock_to_list (include/linux/rculist.h:79 include/linux/rculist.h:128 kernel/locking/lockdep.c:1431)
> [ 8.448151][ T10] ? check_prev_add (kernel/locking/lockdep.c:3213)
> [ 8.448151][ T10] ? validate_chain (kernel/locking/lockdep.c:156 kernel/locking/lockdep.c:185 kernel/locking/lockdep.c:3872)
> [ 8.448151][ T10] ? 0xffffffff81000000
> [ 8.448151][ T10] ? truncate_inode_pages_final (include/linux/spinlock.h:401 mm/truncate.c:455)
> [ 8.448151][ T10] ? kvm_sched_clock_read (arch/x86/kernel/kvmclock.c:91)
> [ 8.448151][ T10] ? mark_held_locks (kernel/locking/lockdep.c:4273)
> [ 8.448151][ T10] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4422)
> [ 8.448151][ T10] evict (fs/inode.c:672)
> [ 8.448151][ T10] do_unlinkat (fs/namei.c:4493)
> [ 8.448151][ T10] clean_path (init/initramfs.c:341)
> [ 8.448151][ T10] do_symlink (init/initramfs.c:428)
> [ 8.448151][ T10] ? do_collect (init/initramfs.c:267)
> [ 8.448151][ T10] flush_buffer (init/initramfs.c:452 init/initramfs.c:464)
> [ 8.448151][ T10] ? bunzip2 (lib/decompress_inflate.c:37)
> [ 8.448151][ T10] ? do_name (init/initramfs.c:458)
> [ 8.448151][ T10] __gunzip+0x2b0/0x380
> [ 8.448151][ T10] unpack_to_rootfs (init/initramfs.c:522)
> [ 8.448151][ T10] ? initrd_load (init/initramfs.c:59)
> [ 8.448151][ T10] do_populate_rootfs (init/initramfs.c:706 (discriminator 1))
> [ 8.448151][ T10] async_run_entry_fn (kernel/async.c:136)
> [ 8.448151][ T10] process_one_work (kernel/workqueue.c:3236)
> [ 8.448151][ T10] ? process_one_work (kernel/workqueue.c:3207)
> [ 8.448151][ T10] ? worker_thread (kernel/workqueue.c:3349)
> [ 8.448151][ T10] worker_thread (kernel/workqueue.c:3306 kernel/workqueue.c:3390)
> [ 8.448151][ T10] ? rescuer_thread (kernel/workqueue.c:3339)
> [ 8.448151][ T10] kthread (kernel/kthread.c:389)
> [ 8.448151][ T10] ? kthread_park (kernel/kthread.c:342)
> [ 8.448151][ T10] ret_from_fork (arch/x86/kernel/process.c:153)
> [ 8.448151][ T10] ? kthread_park (kernel/kthread.c:342)
> [ 8.448151][ T10] ret_from_fork_asm (arch/x86/entry/entry_64.S:254)
> [ 8.448151][ T10] </TASK>
> [ 8.949844][ T10] Freeing initrd memory: 198316K
>
>
>
> The kernel config and materials to reproduce are available at:
> https://download.01.org/0day-ci/archive/20240825/202408251605.df83b338-lkp@intel.com
>
>
>
> --
> 0-DAY CI Kernel Test Service
> https://github.com/intel/lkp-tests/wiki
>
^ permalink raw reply [flat|nested] 2+ messages in thread
end of thread, other threads:[~2024-08-26 16:17 UTC | newest]
Thread overview: 2+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2024-08-25 8:46 [paulmckrcu:dev] [mm/filemap] 199735cdc2: BUG:sleeping_function_called_from_invalid_context_at_mm/filemap.c kernel test robot
2024-08-26 16:17 ` Paul E. McKenney
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).