INFO: task hung in htab_map

Kernel	Title	Rank 🛈	Repro	Cause bisect	Fix bisect	Count	Last	Reported	Patched	Status
android-49	INFO: task hung in htab_map_free	1				2	2571d	2528d	0/3	auto-closed as invalid on 2019/08/29 11:50
INFO: task kworker/0:59:13086 blocked for more than 143 seconds.
      Not tainted syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/0:59    state:D stack:23984 pid:13086 ppid:     2 flags:0x00004000
Workqueue: events bpf_map_free_deferred
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5049 [inline]
 __schedule+0x11ef/0x43c0 kernel/sched/core.c:6395
 schedule+0x11b/0x1e0 kernel/sched/core.c:6478
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6537
 __mutex_lock_common+0xcfc/0x2400 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x17/0x20 kernel/locking/mutex.c:743
 rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
 htab_map_free+0x25/0x5f0 kernel/bpf/hashtab.c:1473
 process_one_work+0x85f/0x1010 kernel/workqueue.c:2310
 worker_thread+0xaa6/0x1290 kernel/workqueue.c:2457
 kthread+0x436/0x520 kernel/kthread.c:334
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:287
 </TASK>
INFO: task kworker/0:64:13091 blocked for more than 144 seconds.
      Not tainted syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/0:64    state:D stack:24056 pid:13091 ppid:     2 flags:0x00004000
Workqueue: events bpf_map_free_deferred
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5049 [inline]
 __schedule+0x11ef/0x43c0 kernel/sched/core.c:6395
 schedule+0x11b/0x1e0 kernel/sched/core.c:6478
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6537
 __mutex_lock_common+0xcfc/0x2400 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x17/0x20 kernel/locking/mutex.c:743
 rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
 dev_map_free+0x123/0x680 kernel/bpf/devmap.c:206
 process_one_work+0x85f/0x1010 kernel/workqueue.c:2310
 worker_thread+0xaa6/0x1290 kernel/workqueue.c:2457
 kthread+0x436/0x520 kernel/kthread.c:334
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:287
 </TASK>
INFO: task kworker/u4:7:19758 blocked for more than 145 seconds.
      Not tainted syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/u4:7    state:D stack:22736 pid:19758 ppid:     2 flags:0x00004000
Workqueue: netns cleanup_net
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5049 [inline]
 __schedule+0x11ef/0x43c0 kernel/sched/core.c:6395
 schedule+0x11b/0x1e0 kernel/sched/core.c:6478
 schedule_timeout+0xbd/0x2d0 kernel/time/timer.c:2093
 do_wait_for_common+0x2a2/0x450 kernel/sched/completion.c:85
 __wait_for_common kernel/sched/completion.c:106 [inline]
 wait_for_common kernel/sched/completion.c:117 [inline]
 wait_for_completion+0x48/0x60 kernel/sched/completion.c:138
 rcu_barrier+0x406/0x4c0 kernel/rcu/tree.c:4108
 l2tp_exit_net+0x20d/0x2c0 net/l2tp/l2tp_core.c:1685
 ops_exit_list net/core/net_namespace.c:172 [inline]
 cleanup_net+0x706/0xba0 net/core/net_namespace.c:635
 process_one_work+0x85f/0x1010 kernel/workqueue.c:2310
 worker_thread+0xaa6/0x1290 kernel/workqueue.c:2457
 kthread+0x436/0x520 kernel/kthread.c:334
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:287
 </TASK>
INFO: task syz.8.6949:29598 blocked for more than 146 seconds.
      Not tainted syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz.8.6949      state:D stack:26288 pid:29598 ppid: 20376 flags:0x00004004
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5049 [inline]
 __schedule+0x11ef/0x43c0 kernel/sched/core.c:6395
 schedule+0x11b/0x1e0 kernel/sched/core.c:6478
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6537
 __mutex_lock_common+0xcfc/0x2400 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x17/0x20 kernel/locking/mutex.c:743
 rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
 netdev_run_todo+0x2fd/0xa70 net/core/dev.c:10688
 tun_detach drivers/net/tun.c:705 [inline]
 tun_chr_close+0x13b/0x1b0 drivers/net/tun.c:3440
 __fput+0x234/0x930 fs/file_table.c:311
 task_work_run+0x125/0x1a0 kernel/task_work.c:188
 tracehook_notify_resume include/linux/tracehook.h:189 [inline]
 exit_to_user_mode_loop+0x10f/0x130 kernel/entry/common.c:181
 exit_to_user_mode_prepare+0xee/0x180 kernel/entry/common.c:214
 __syscall_exit_to_user_mode_work kernel/entry/common.c:296 [inline]
 syscall_exit_to_user_mode+0x16/0x40 kernel/entry/common.c:307
 do_syscall_64+0x58/0xa0 arch/x86/entry/common.c:86
 entry_SYSCALL_64_after_hwframe+0x66/0xd0
RIP: 0033:0x7f1dffc30799
RSP: 002b:00007ffe516a7248 EFLAGS: 00000246 ORIG_RAX: 00000000000001b4
RAX: 0000000000000000 RBX: 00007f1dffeabda0 RCX: 00007f1dffc30799
RDX: 0000000000000000 RSI: 000000000000001e RDI: 0000000000000003
RBP: 00007f1dffeabda0 R08: 00007f1dffeaa038 R09: 0000000000000000
R10: 00000000003ffd2c R11: 0000000000000246 R12: 00000000000fc83e
R13: 00007f1dffeaa09c R14: 00000000000fc5b2 R15: 00007f1dffeaa090
 </TASK>
INFO: task syz.9.6951:29607 blocked for more than 147 seconds.
      Not tainted syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz.9.6951      state:D stack:27152 pid:29607 ppid: 24637 flags:0x00004004
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5049 [inline]
 __schedule+0x11ef/0x43c0 kernel/sched/core.c:6395
 schedule+0x11b/0x1e0 kernel/sched/core.c:6478
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6537
 __mutex_lock_common+0xcfc/0x2400 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x17/0x20 kernel/locking/mutex.c:743
 rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
 netdev_run_todo+0x2fd/0xa70 net/core/dev.c:10688
 tun_detach drivers/net/tun.c:705 [inline]
 tun_chr_close+0x13b/0x1b0 drivers/net/tun.c:3440
 __fput+0x234/0x930 fs/file_table.c:311
 task_work_run+0x125/0x1a0 kernel/task_work.c:188
 tracehook_notify_resume include/linux/tracehook.h:189 [inline]
 exit_to_user_mode_loop+0x10f/0x130 kernel/entry/common.c:181
 exit_to_user_mode_prepare+0xee/0x180 kernel/entry/common.c:214
 __syscall_exit_to_user_mode_work kernel/entry/common.c:296 [inline]
 syscall_exit_to_user_mode+0x16/0x40 kernel/entry/common.c:307
 do_syscall_64+0x58/0xa0 arch/x86/entry/common.c:86
 entry_SYSCALL_64_after_hwframe+0x66/0xd0
RIP: 0033:0x7fcc86ed2799
RSP: 002b:00007ffcd07b30f8 EFLAGS: 00000246 ORIG_RAX: 00000000000001b4
RAX: 0000000000000000 RBX: 00007fcc8714dda0 RCX: 00007fcc86ed2799
RDX: 0000000000000000 RSI: 000000000000001e RDI: 0000000000000003
RBP: 00007fcc8714dda0 R08: 0000000000000006 R09: 0000000000000000
R10: 00007fcc8714dcb0 R11: 0000000000000246 R12: 00000000000fc919
R13: 00007fcc8714c09c R14: 00000000000fc6ea R15: 00007fcc8714c090
 </TASK>
INFO: task syz.2.6953:29626 blocked for more than 148 seconds.
      Not tainted syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz.2.6953      state:D stack:27440 pid:29626 ppid: 28180 flags:0x00004002
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5049 [inline]
 __schedule+0x11ef/0x43c0 kernel/sched/core.c:6395
 schedule+0x11b/0x1e0 kernel/sched/core.c:6478
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6537
 __mutex_lock_common+0xcfc/0x2400 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x17/0x20 kernel/locking/mutex.c:743
 rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
 netdev_run_todo+0x2fd/0xa70 net/core/dev.c:10688
 tun_detach drivers/net/tun.c:705 [inline]
 tun_chr_close+0x13b/0x1b0 drivers/net/tun.c:3440
 __fput+0x234/0x930 fs/file_table.c:311
 task_work_run+0x125/0x1a0 kernel/task_work.c:188
 exit_task_work include/linux/task_work.h:33 [inline]
 do_exit+0x626/0x20c0 kernel/exit.c:883
 __do_sys_exit kernel/exit.c:966 [inline]
 __se_sys_exit kernel/exit.c:964 [inline]
 __x64_sys_exit+0x3c/0x40 kernel/exit.c:964
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x4c/0xa0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x66/0xd0
RIP: 0033:0x7f5118fa1799
RSP: 002b:00007f51171fafd8 EFLAGS: 00000246 ORIG_RAX: 000000000000003c
RAX: ffffffffffffffda RBX: 00007f511921afa0 RCX: 00007f5118fa1799
RDX: 00007f51171fb9c8 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007f5119037bd9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007f511921b038 R14: 00007f511921afa0 R15: 00007ffd99740b08
 </TASK>
INFO: task syz.5.6958:29636 blocked for more than 149 seconds.
      Not tainted syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz.5.6958      state:D stack:27728 pid:29636 ppid: 28231 flags:0x00004002
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5049 [inline]
 __schedule+0x11ef/0x43c0 kernel/sched/core.c:6395
 schedule+0x11b/0x1e0 kernel/sched/core.c:6478
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6537
 __mutex_lock_common+0xcfc/0x2400 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x17/0x20 kernel/locking/mutex.c:743
 rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
 netdev_run_todo+0x2fd/0xa70 net/core/dev.c:10688
 ppp_release+0xf1/0x1f0 drivers/net/ppp/ppp_generic.c:420
 __fput+0x234/0x930 fs/file_table.c:311
 task_work_run+0x125/0x1a0 kernel/task_work.c:188
 exit_task_work include/linux/task_work.h:33 [inline]
 do_exit+0x626/0x20c0 kernel/exit.c:883
 __do_sys_exit kernel/exit.c:966 [inline]
 __se_sys_exit kernel/exit.c:964 [inline]
 __x64_sys_exit+0x3c/0x40 kernel/exit.c:964
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x4c/0xa0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x66/0xd0
RIP: 0033:0x7fcc2b064799
RSP: 002b:00007fcc292bdfd8 EFLAGS: 00000246 ORIG_RAX: 000000000000003c
RAX: ffffffffffffffda RBX: 00007fcc2b2ddfa0 RCX: 00007fcc2b064799
RDX: 00007fcc292be9c8 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007fcc2b0fabd9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fcc2b2de038 R14: 00007fcc2b2ddfa0 R15: 00007ffe82bd8228
 </TASK>

Showing all locks held in the system:
3 locks held by ksoftirqd/0/14:
1 lock held by khungtaskd/27:
 #0: ffffffff8c31eaa0 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x0/0x30
2 locks held by getty/3950:
 #0: ffff88814c7e0098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x21/0x70 drivers/tty/tty_ldisc.c:252
 #1: ffffc90002cf62e8 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x5df/0x1a70 drivers/tty/n_tty.c:2158
2 locks held by kworker/1:24/4705:
 #0: ffff888016c72138 ((wq_completion)rcu_gp){+.+.}-{0:0}, at: process_one_work+0x761/0x1010 kernel/workqueue.c:-1
 #1: ffffc900037efd00 ((work_completion)(&rew.rew_work)){+.+.}-{0:0}, at: process_one_work+0x79f/0x1010 kernel/workqueue.c:2285
3 locks held by kworker/0:27/9437:
 #0: ffff888016c70938 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x761/0x1010 kernel/workqueue.c:-1
 #1: ffffc90002ddfd00 ((work_completion)(&(&nsim_dev->trap_data->trap_report_dw)->work)){+.+.}-{0:0}, at: process_one_work+0x79f/0x1010 kernel/workqueue.c:2285
 #2: ffff88804feb2400 (&nsim_dev->port_list_lock#2){+.+.}-{3:3}, at: nsim_dev_trap_report_work+0x56/0xb40 drivers/net/netdevsim/dev.c:757
3 locks held by kworker/0:59/13086:
 #0: ffff888016c70938 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x761/0x1010 kernel/workqueue.c:-1
 #1: ffffc9000438fd00 ((work_completion)(&map->work)){+.+.}-{0:0}, at: process_one_work+0x79f/0x1010 kernel/workqueue.c:2285
 #2: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
2 locks held by kworker/0:60/13087:
3 locks held by kworker/0:64/13091:
 #0: ffff888016c70938 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x761/0x1010 kernel/workqueue.c:-1
 #1: ffffc900043dfd00 ((work_completion)(&map->work)){+.+.}-{0:0}, at: process_one_work+0x79f/0x1010 kernel/workqueue.c:2285
 #2: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
2 locks held by kworker/u4:2/19753:
4 locks held by kworker/u4:7/19758:
 #0: ffff888016dcd938 ((wq_completion)netns){+.+.}-{0:0}, at: process_one_work+0x761/0x1010 kernel/workqueue.c:-1
 #1: ffffc900033afd00 (net_cleanup_work){+.+.}-{0:0}, at: process_one_work+0x79f/0x1010 kernel/workqueue.c:2285
 #2: ffffffff8d430c50 (pernet_ops_rwsem){++++}-{3:3}, at: cleanup_net+0x148/0xba0 net/core/net_namespace.c:589
 #3: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
3 locks held by kworker/0:0/21798:
3 locks held by kworker/1:1/24954:
 #0: ffff888016c70938 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x761/0x1010 kernel/workqueue.c:-1
 #1: ffffc900030efd00 ((work_completion)(&map->work)){+.+.}-{0:0}, at: process_one_work+0x79f/0x1010 kernel/workqueue.c:2285
 #2: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
1 lock held by syz-executor/28180:
 #0: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
1 lock held by syz.8.6949/29598:
 #0: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
1 lock held by syz.9.6951/29607:
 #0: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
1 lock held by syz.2.6953/29626:
 #0: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
1 lock held by syz.5.6958/29636:
 #0: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
1 lock held by syz.2.6987/29753:
 #0: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
1 lock held by syz.5.6988/29760:
 #0: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
1 lock held by syz.1.6993/29803:
 #0: ffffffff8c323430 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0xa1/0x4c0 kernel/rcu/tree.c:4043
7 locks held by syz-executor/29794:
 #0: ffff88802bde6460 (sb_writers#8){.+.+}-{0:0}, at: vfs_write+0x295/0xd60 fs/read_write.c:590
 #1: ffff88805f121c88 (&of->mutex){+.+.}-{3:3}, at: kernfs_fop_write_iter+0x1e5/0x4c0 fs/kernfs/file.c:287
 #2: ffff888022b0f660 (kn->active#284){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x208/0x4c0 fs/kernfs/file.c:288
 #3: ffffffff8cd79f88 (nsim_bus_dev_list_lock){+.+.}-{3:3}, at: del_device_store+0xd3/0x3a0 drivers/net/netdevsim/bus.c:344
 #4: ffff88804feb7178 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:764 [inline]
 #4: ffff88804feb7178 (&dev->mutex){....}-{3:3}, at: __device_driver_lock drivers/base/dd.c:1044 [inline]
 #4: ffff88804feb7178 (&dev->mutex){....}-{3:3}, at: device_release_driver_internal+0xb0/0x750 drivers/base/dd.c:1259