syzbot


INFO: task hung in key_garbage_collector

Status: auto-closed as invalid on 2019/04/24 21:05
Subsystems: keyrings lsm
[Documentation on labels]
First crash: 2096d, last: 2096d
Similar bugs (3)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
android-414 INFO: task hung in key_garbage_collector 2 2144d 2146d 0/1 auto-closed as invalid on 2019/03/08 00:41
android-414 INFO: task hung in key_garbage_collector (2) 1 1963d 1927d 0/1 auto-closed as invalid on 2019/09/05 13:15
android-49 INFO: task hung in key_garbage_collector 1 1836d 1836d 0/3 auto-closed as invalid on 2019/11/10 22:30

Sample crash report:
INFO: task kworker/0:1:6954 blocked for more than 140 seconds.
      Not tainted 4.19.0+ #208
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/0:1     D21160  6954      2 0x80000000
Workqueue: events key_garbage_collector
Call Trace:
 context_switch kernel/sched/core.c:2826 [inline]
 __schedule+0x8cf/0x21d0 kernel/sched/core.c:3474
 schedule+0xfe/0x460 kernel/sched/core.c:3518
 exp_funnel_lock kernel/rcu/tree_exp.h:319 [inline]
 _synchronize_rcu_expedited.constprop.58+0x8df/0x9d0 kernel/rcu/tree_exp.h:620
 synchronize_rcu_expedited+0x27/0xa0 kernel/rcu/tree_exp.h:758
 synchronize_rcu.part.53+0x84/0x120 kernel/rcu/tree_plugin.h:857
 synchronize_rcu+0x27/0xa0 kernel/rcu/tree_plugin.h:860
 key_garbage_collector+0x501/0xb60 security/keys/gc.c:292
 process_one_work+0xc90/0x1c40 kernel/workqueue.c:2153
 process_scheduled_works kernel/workqueue.c:2212 [inline]
 worker_thread+0xad9/0x1390 kernel/workqueue.c:2298
 kthread+0x35a/0x420 kernel/kthread.c:246
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:350
INFO: task syz-executor5:8842 blocked for more than 140 seconds.
      Not tainted 4.19.0+ #208
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor5   D23472  8842  18100 0x20020006
Call Trace:
 context_switch kernel/sched/core.c:2826 [inline]
 __schedule+0x8cf/0x21d0 kernel/sched/core.c:3474
 schedule+0xfe/0x460 kernel/sched/core.c:3518
 exp_funnel_lock kernel/rcu/tree_exp.h:319 [inline]
 _synchronize_rcu_expedited.constprop.58+0x8df/0x9d0 kernel/rcu/tree_exp.h:620
 synchronize_rcu_expedited+0x27/0xa0 kernel/rcu/tree_exp.h:758
 synchronize_net+0x3b/0x60 net/core/dev.c:9062
 packet_set_ring+0x286/0x1da0 net/packet/af_packet.c:4339
 packet_release+0x656/0xda0 net/packet/af_packet.c:2998
 __sock_release+0xd7/0x250 net/socket.c:579
 sock_close+0x19/0x20 net/socket.c:1141
 __fput+0x385/0xa30 fs/file_table.c:278
 ____fput+0x15/0x20 fs/file_table.c:309
 task_work_run+0x1e8/0x2a0 kernel/task_work.c:113
 get_signal+0x1558/0x1980 kernel/signal.c:2347
 do_signal+0x9c/0x21c0 arch/x86/kernel/signal.c:816
 exit_to_usermode_loop+0x2e5/0x380 arch/x86/entry/common.c:162
 prepare_exit_to_usermode arch/x86/entry/common.c:197 [inline]
 syscall_return_slowpath arch/x86/entry/common.c:268 [inline]
 do_syscall_32_irqs_on arch/x86/entry/common.c:341 [inline]
 do_fast_syscall_32+0xcd5/0xfb2 arch/x86/entry/common.c:397
 entry_SYSENTER_compat+0x70/0x7f arch/x86/entry/entry_64_compat.S:139
RIP: 0023:0xf7f23a29
Code: Bad RIP value.
RSP: 002b:00000000f5efe0cc EFLAGS: 00000296 ORIG_RAX: 000000000000016e
RAX: 0000000000000000 RBX: 0000000000000006 RCX: 0000000000000107
RDX: 0000000000000005 RSI: 0000000020001000 RDI: 00000000000000c5
RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000

Showing all locks held in the system:
1 lock held by khungtaskd/983:
 #0: 00000000cd696305 (rcu_read_lock){....}, at: debug_show_all_locks+0xd0/0x424 kernel/locking/lockdep.c:4379
1 lock held by rsyslogd/5434:
 #0: 00000000353a69f0 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x1bb/0x200 fs/file.c:766
2 locks held by getty/5523:
 #0: 000000006406d32b (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
 #1: 00000000f0c98a74 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5524:
 #0: 0000000087dd1fbc (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
 #1: 00000000e31c77a9 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5525:
 #0: 000000004081afe4 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
 #1: 00000000bb715c5f (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5526:
 #0: 00000000c82ef620 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
 #1: 00000000cc5541e9 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5527:
 #0: 000000008e23cacb (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
 #1: 0000000042c94a84 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5528:
 #0: 00000000a9b3b99a (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
 #1: 000000007f35f41f (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5529:
 #0: 00000000b4655cf1 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
 #1: 00000000f73af64b (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by kworker/0:1/6954:
 #0: 00000000b4ee34b4 ((wq_completion)"events"){+.+.}, at: __write_once_size include/linux/compiler.h:206 [inline]
 #0: 00000000b4ee34b4 ((wq_completion)"events"){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
 #0: 00000000b4ee34b4 ((wq_completion)"events"){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:40 [inline]
 #0: 00000000b4ee34b4 ((wq_completion)"events"){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:59 [inline]
 #0: 00000000b4ee34b4 ((wq_completion)"events"){+.+.}, at: set_work_data kernel/workqueue.c:617 [inline]
 #0: 00000000b4ee34b4 ((wq_completion)"events"){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline]
 #0: 00000000b4ee34b4 ((wq_completion)"events"){+.+.}, at: process_one_work+0xb43/0x1c40 kernel/workqueue.c:2124
 #1: 0000000029ccf08b (key_gc_work){+.+.}, at: process_one_work+0xb9a/0x1c40 kernel/workqueue.c:2128
2 locks held by syz-executor5/8842:
 #0: 00000000da25f87b (&sb->s_type->i_mutex_key#11){+.+.}, at: inode_lock include/linux/fs.h:738 [inline]
 #0: 00000000da25f87b (&sb->s_type->i_mutex_key#11){+.+.}, at: __sock_release+0x8b/0x250 net/socket.c:578
 #1: 000000004be3187c (sk_lock-AF_PACKET){+.+.}, at: lock_sock include/net/sock.h:1492 [inline]
 #1: 000000004be3187c (sk_lock-AF_PACKET){+.+.}, at: packet_release+0x5ff/0xda0 net/packet/af_packet.c:2995

=============================================

NMI backtrace for cpu 1
CPU: 1 PID: 983 Comm: khungtaskd Not tainted 4.19.0+ #208
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 __dump_stack lib/dump_stack.c:77 [inline]
 dump_stack+0x244/0x39d lib/dump_stack.c:113
 nmi_cpu_backtrace.cold.1+0x5c/0xa1 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x1b3/0x1ed 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:144 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:204 [inline]
 watchdog+0xb3e/0x1050 kernel/hung_task.c:265
 kthread+0x35a/0x420 kernel/kthread.c:246
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:350
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.19.0+ #208
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
INFO: NMI handler (nmi_cpu_backtrace_handler) took too long to run: 1.778 msecs
RIP: 0010:update_sg_lb_stats kernel/sched/fair.c:7909 [inline]
RIP: 0010:update_sd_lb_stats+0x97e/0x3230 kernel/sched/fair.c:8084
Code: 48 89 f8 48 c1 e8 03 42 0f b6 14 38 48 89 f8 83 e0 07 83 c0 03 38 d0 7c 08 84 d2 0f 85 f4 17 00 00 83 43 34 01 e9 22 fe ff ff <4c> 89 f0 48 01 f0 48 8d b8 68 01 00 00 48 89 fa 48 c1 ea 03 42 80
RSP: 0018:ffff8801dae06c08 EFLAGS: 00000246
RAX: ffff8801dae07348 RBX: ffff8801dae06d88 RCX: 0000000000000001
RDX: 0000000000000000 RSI: ffff8801daf00000 RDI: 0000000000000007
RBP: ffff8801dae06fb0 R08: 0000000000000001 R09: ffffffff89018028
R10: ffffed003b5c0dbb R11: ffff8801dae06ddf R12: ffff8801daf2cc40
R13: ffff8801dae07020 R14: 000000000002cc40 R15: dffffc0000000000
FS:  0000000000000000(0000) GS:ffff8801dae00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: ffffffffff600400 CR3: 00000001bdd41000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 find_busiest_group+0xf9/0x2060 kernel/sched/fair.c:8362
 load_balance+0x687/0x39a0 kernel/sched/fair.c:8659
 rebalance_domains+0x845/0xdc0 kernel/sched/fair.c:9075
 _nohz_idle_balance+0x621/0x7a0 kernel/sched/fair.c:9461
 nohz_idle_balance kernel/sched/fair.c:9509 [inline]
 run_rebalance_domains+0x2ec/0x500 kernel/sched/fair.c:9692
 __do_softirq+0x308/0xb7e kernel/softirq.c:292
 invoke_softirq kernel/softirq.c:373 [inline]
 irq_exit+0x17f/0x1c0 kernel/softirq.c:413
 scheduler_ipi+0x55a/0xad0 kernel/sched/core.c:1781
 smp_reschedule_interrupt+0x109/0x650 arch/x86/kernel/smp.c:278
 reschedule_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:825
 </IRQ>
RIP: 0010:native_safe_halt+0x6/0x10 arch/x86/include/asm/irqflags.h:57
Code: e9 2c ff ff ff 48 89 c7 48 89 45 d8 e8 73 13 06 fa 48 8b 45 d8 e9 ca fe ff ff 48 89 df e8 62 13 06 fa eb 82 55 48 89 e5 fb f4 <5d> c3 0f 1f 84 00 00 00 00 00 55 48 89 e5 f4 5d c3 90 90 90 90 90
RSP: 0018:ffffffff89207c10 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff02
RAX: dffffc0000000000 RBX: 1ffffffff1240f86 RCX: 0000000000000000
RDX: 1ffffffff1263f91 RSI: 0000000000000001 RDI: ffffffff8931fc88
RBP: ffffffff89207c10 R08: ffffffff89276e40 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff89207cd0
R13: ffffffff89f2ee60 R14: 0000000000000000 R15: 0000000000000000
 arch_safe_halt arch/x86/include/asm/paravirt.h:151 [inline]
 default_idle+0xbf/0x490 arch/x86/kernel/process.c:498
 arch_cpu_idle+0x10/0x20 arch/x86/kernel/process.c:489
 default_idle_call+0x6d/0x90 kernel/sched/idle.c:93
 cpuidle_idle_call kernel/sched/idle.c:153 [inline]
 do_idle+0x49b/0x5c0 kernel/sched/idle.c:262
 cpu_startup_entry+0x18/0x20 kernel/sched/idle.c:353
 rest_init+0x243/0x372 init/main.c:442
 arch_call_rest_init+0xe/0x1b
 start_kernel+0xa1f/0xa5a init/main.c:744
 x86_64_start_reservations+0x2e/0x30 arch/x86/kernel/head64.c:472
 x86_64_start_kernel+0x76/0x79 arch/x86/kernel/head64.c:451
 secondary_startup_64+0xa4/0xb0 arch/x86/kernel/head_64.S:243

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2018/10/26 20:59 upstream 18d0eae30e6a a8292de9 .config console log report ci-upstream-kasan-gce-386
* Struck through repros no longer work on HEAD.