INFO: task kworker/0:1:13 blocked for more than 143 seconds.
Not tainted 5.4.0-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/0:1 D26392 13 2 0x80004000
Workqueue: events key_garbage_collector
Call Trace:
context_switch kernel/sched/core.c:3385 [inline]
__schedule+0x8e1/0x1f30 kernel/sched/core.c:4081
schedule+0xdc/0x2b0 kernel/sched/core.c:4155
schedule_timeout+0x717/0xc50 kernel/time/timer.c:1871
do_wait_for_common kernel/sched/completion.c:83 [inline]
__wait_for_common kernel/sched/completion.c:104 [inline]
wait_for_common kernel/sched/completion.c:115 [inline]
wait_for_completion+0x29c/0x440 kernel/sched/completion.c:136
__wait_rcu_gp+0x225/0x2f0 kernel/rcu/update.c:374
synchronize_rcu.part.0+0xcf/0xe0 kernel/rcu/tree.c:2768
synchronize_rcu+0x27/0xa0 kernel/rcu/tree.c:2769
key_garbage_collector+0x3d5/0x940 security/keys/gc.c:287
process_one_work+0x9af/0x1740 kernel/workqueue.c:2264
worker_thread+0x98/0xe40 kernel/workqueue.c:2410
kthread+0x361/0x430 kernel/kthread.c:255
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:352
Showing all locks held in the system:
2 locks held by kworker/0:1/13:
#0: ffff88802cc27d28 ((wq_completion)events){+.+.}, at: __write_once_size include/linux/compiler.h:226 [inline]
#0: ffff88802cc27d28 ((wq_completion)events){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff88802cc27d28 ((wq_completion)events){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:855 [inline]
#0: ffff88802cc27d28 ((wq_completion)events){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:40 [inline]
#0: ffff88802cc27d28 ((wq_completion)events){+.+.}, at: set_work_data kernel/workqueue.c:615 [inline]
#0: ffff88802cc27d28 ((wq_completion)events){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:642 [inline]
#0: ffff88802cc27d28 ((wq_completion)events){+.+.}, at: process_one_work+0x88b/0x1740 kernel/workqueue.c:2235
#1: ffff88802c4b7dc0 (key_gc_work){+.+.}, at: process_one_work+0x8c1/0x1740 kernel/workqueue.c:2239
1 lock held by khungtaskd/1114:
#0: ffffffff895a4080 (rcu_read_lock){....}, at: debug_show_all_locks+0x5f/0x279 kernel/locking/lockdep.c:5334
2 locks held by rsyslogd/7744:
#0: ffff88802674c860 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xee/0x110 fs/file.c:801
#1: ffff88802d537398 (&rq->lock){-.-.}, at: newidle_balance+0xa28/0xe80 kernel/sched/fair.c:10166
2 locks held by getty/7867:
#0: ffff88802a100090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340
#1: ffffc900040122e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1c10 drivers/tty/n_tty.c:2156
2 locks held by getty/7868:
#0: ffff888027013090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340
#1: ffffc900040162e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1c10 drivers/tty/n_tty.c:2156
2 locks held by getty/7869:
#0: ffff88802a78e090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340
#1: ffffc9000400e2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1c10 drivers/tty/n_tty.c:2156
2 locks held by getty/7870:
#0: ffff88802a9fb090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340
#1: ffffc900040062e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1c10 drivers/tty/n_tty.c:2156
2 locks held by getty/7871:
#0: ffff88802aeff090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340
#1: ffffc900026fa2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1c10 drivers/tty/n_tty.c:2156
2 locks held by getty/7872:
#0: ffff88801aedb090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340
#1: ffffc9000401a2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1c10 drivers/tty/n_tty.c:2156
2 locks held by getty/7873:
#0: ffff8880205ad090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340
#1: ffffc900026ea2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1c10 drivers/tty/n_tty.c:2156
3 locks held by syz-executor.1/7948:
=============================================
NMI backtrace for cpu 2
CPU: 2 PID: 1114 Comm: khungtaskd Not tainted 5.4.0-syzkaller #0
Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.12.0-59-gc9ba5276e321-prebuilt.qemu.org 04/01/2014
Call Trace:
__dump_stack lib/dump_stack.c:77 [inline]
dump_stack+0x197/0x210 lib/dump_stack.c:118
nmi_cpu_backtrace.cold+0x70/0xb2 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x23b/0x28b lib/nmi_backtrace.c:62
arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:205 [inline]
watchdog+0xb11/0x10c0 kernel/hung_task.c:289
kthread+0x361/0x430 kernel/kthread.c:255
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:352
Sending NMI from CPU 2 to CPUs 0-1,3:
NMI backtrace for cpu 0 skipped: idling at native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:60
NMI backtrace for cpu 3 skipped: idling at native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:60
NMI backtrace for cpu 1
CPU: 1 PID: 7948 Comm: syz-executor.1 Not tainted 5.4.0-syzkaller #0
Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.12.0-59-gc9ba5276e321-prebuilt.qemu.org 04/01/2014
RIP: 0010:perf_swevent_hrtimer+0x271/0x3e0 kernel/events/core.c:9813
Code: 6d 24 31 ff 41 83 e5 02 44 89 ee e8 29 ce e8 ff 45 85 ed 75 32 e8 9f cc e8 ff 4c 89 f7 4c 89 f9 be 01 00 00 00 48 8d 54 24 40 <45> 31 f6 e8 17 fa ff ff 31 ff 41 89 c5 89 c6 e8 fb cd e8 ff 45 85
RSP: 0018:ffff88802d3095c0 EFLAGS: 00000006
RAX: ffff8880716b4200 RBX: 1ffff11005a612b8 RCX: ffff88802d309a28
RDX: ffff88802d309600 RSI: 0000000000000001 RDI: ffff888020c65000
RBP: ffff88802d309828 R08: ffff8880716b4200 R09: ffffed100418ca3d
R10: ffffed100418ca3c R11: ffff888020c651e7 R12: ffff888020c65160
R13: 0000000000000000 R14: ffff888020c65000 R15: ffff88802d309a28
FS: 00007f30257f0700(0000) GS:ffff88802d300000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: ffffffffff600400 CR3: 000000002471a000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
__run_hrtimer kernel/time/hrtimer.c:1514 [inline]
__hrtimer_run_queues+0x364/0xe40 kernel/time/hrtimer.c:1576
hrtimer_interrupt+0x314/0x770 kernel/time/hrtimer.c:1638
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1110 [inline]
smp_apic_timer_interrupt+0x160/0x610 arch/x86/kernel/apic/apic.c:1135
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:829
RIP: 0010:native_read_msr_safe+0x4/0x40 arch/x86/include/asm/msr.h:142
Code: 44 00 00 c3 55 48 c1 e2 20 89 f6 48 89 e5 48 09 d6 48 83 ec 08 89 c2 89 45 fc e8 07 a0 6f 02 8b 45 fc c9 c3 66 90 89 f9 0f 32 <31> c9 89 0e 0f 1f 44 00 00 48 c1 e2 20 48 09 d0 c3 55 48 c1 e2 20
RSP: 0018:ffff88802d309ad8 EFLAGS: 00000206 ORIG_RAX: ffffffffffffff13
RAX: 0000000000000000 RBX: 1ffff11005a6135d RCX: 000000000000017a
RDX: 0000000000000000 RSI: ffff88802d309b08 RDI: 000000000000017a
RBP: ffff88802d309b70 R08: ffff8880716b4200 R09: ffff88802d309b08
R10: ffffed1005a61389 R11: ffff88802d309c4f R12: ffff88802d309b48
R13: 000000000000017a R14: dffffc0000000000 R15: 000000000001b200
mce_gather_info arch/x86/kernel/cpu/mce/core.c:438 [inline]
machine_check_poll+0xeb/0x500 arch/x86/kernel/cpu/mce/core.c:706
mce_timer_fn+0x18e/0x220 arch/x86/kernel/cpu/mce/core.c:1429
call_timer_fn+0x1ac/0x780 kernel/time/timer.c:1404
expire_timers kernel/time/timer.c:1449 [inline]
__run_timers kernel/time/timer.c:1773 [inline]
__run_timers kernel/time/timer.c:1740 [inline]
run_timer_softirq+0x6c3/0x1790 kernel/time/timer.c:1786
__do_softirq+0x262/0x98c kernel/softirq.c:292
invoke_softirq kernel/softirq.c:373 [inline]
irq_exit+0x19b/0x1e0 kernel/softirq.c:413
exiting_irq arch/x86/include/asm/apic.h:536 [inline]
smp_apic_timer_interrupt+0x1a3/0x610 arch/x86/kernel/apic/apic.c:1137
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:829
RIP: 0010:__raw_spin_unlock_irq include/linux/spinlock_api_smp.h:169 [inline]
RIP: 0010:_raw_spin_unlock_irq+0x4f/0x80 kernel/locking/spinlock.c:199
Code: c0 68 34 53 89 48 ba 00 00 00 00 00 fc ff df 48 c1 e8 03 80 3c 10 00 75 33 48 83 3d 12 2a 99 01 00 74 20 fb 66 0f 1f 44 00 00 01 00 00 00 e8 e7 6b 96 f9 65 8b 05 18 14 48 78 85 c0 74 06 41
RSP: 0018:ffff8880716bfb10 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13
RAX: 1ffffffff12a668d RBX: ffff8880716b4200 RCX: 0000000000000006
RDX: dffffc0000000000 RSI: 0000000000000008 RDI: ffff8880716b4a94
RBP: ffff8880716bfb18 R08: 1ffffffff15d29b8 R09: fffffbfff15d29b9
R10: fffffbfff15d29b8 R11: ffffffff8ae94dc7 R12: ffff88802d337380
R13: ffff88802c4b8380 R14: ffff888023802800 R15: 0000000000000000
finish_lock_switch kernel/sched/core.c:3124 [inline]
finish_task_switch+0x147/0x750 kernel/sched/core.c:3224
context_switch kernel/sched/core.c:3388 [inline]
__schedule+0x8e9/0x1f30 kernel/sched/core.c:4081
preempt_schedule_common+0x4f/0xe0 kernel/sched/core.c:4236
preempt_schedule+0x4b/0x60 kernel/sched/core.c:4261
___preempt_schedule+0x16/0x18 arch/x86/entry/thunk_64.S:50
__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:161 [inline]
_raw_spin_unlock_irqrestore+0xb8/0xe0 kernel/locking/spinlock.c:191
perf_unpin_context+0x55/0x70 kernel/events/core.c:1443
__do_sys_perf_event_open+0x1cc4/0x2c70 kernel/events/core.c:11544
__se_sys_perf_event_open kernel/events/core.c:11151 [inline]
__x64_sys_perf_event_open+0xbe/0x150 kernel/events/core.c:11151
do_syscall_64+0xfa/0x790 arch/x86/entry/common.c:294
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45a759
Code: bd b1 fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 0f 83 8b b1 fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007f30257efc88 EFLAGS: 00000246 ORIG_RAX: 000000000000012a
RAX: ffffffffffffffda RBX: 000000000071bf00 RCX: 000000000045a759
RDX: ffffffffffffffff RSI: 0000000000000000 RDI: 000000002001d000
RBP: 0000000000000005 R08: 0000000000000000 R09: 0000000000000000
R10: ffffffffffffffff R11: 0000000000000246 R12: 00007f30257f06d4
R13: 00000000004aec2b R14: 00000000006f1ca8 R15: 00000000ffffffff