syzbot


INFO: task hung in cangw_pernet_exit (2)

Status: auto-obsoleted due to no activity on 2022/11/15 03:06
Reported-by: syzbot+b8e886effece69f5fcc8@syzkaller.appspotmail.com
First crash: 430d, last: 194d
similar bugs (4):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-4.19 INFO: task hung in cangw_pernet_exit 1 924d 924d 0/1 auto-closed as invalid on 2020/11/15 21:42
upstream INFO: task hung in cangw_pernet_exit 14 1268d 1765d 0/24 closed as invalid on 2019/10/23 07:27
upstream INFO: task hung in cangw_pernet_exit (2) 85 671d 847d 0/24 auto-closed as invalid on 2021/06/26 13:07
upstream INFO: task hung in cangw_pernet_exit (3) syz unreliable 290 397d 503d 0/24 closed as invalid on 2022/02/08 10:22

Sample crash report:
ieee802154 phy1 wpan1: encryption failed: -22
ieee802154 phy0 wpan0: encryption failed: -22
ieee802154 phy1 wpan1: encryption failed: -22
ieee802154 phy0 wpan0: encryption failed: -22
ieee802154 phy1 wpan1: encryption failed: -22
INFO: task kworker/u4:4:493 blocked for more than 140 seconds.
      Not tainted 4.19.211-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/u4:4    D25256   493      2 0x80000000
Workqueue: netns cleanup_net
Call Trace:
 context_switch kernel/sched/core.c:2828 [inline]
 __schedule+0x887/0x2040 kernel/sched/core.c:3517
 schedule+0x8d/0x1b0 kernel/sched/core.c:3561
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
 __mutex_lock_common kernel/locking/mutex.c:1016 [inline]
 __mutex_lock+0x5f0/0x1190 kernel/locking/mutex.c:1078
 cangw_pernet_exit+0xe/0x20 net/can/gw.c:1032
 ops_exit_list+0xa5/0x150 net/core/net_namespace.c:153
 cleanup_net+0x3b4/0x8b0 net/core/net_namespace.c:554
 process_one_work+0x864/0x1570 kernel/workqueue.c:2153
 worker_thread+0x64c/0x1130 kernel/workqueue.c:2296
 kthread+0x33f/0x460 kernel/kthread.c:259
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415

Showing all locks held in the system:
3 locks held by kworker/0:1/14:
 #0: 00000000e607a13b ((wq_completion)"%s"("ipv6_addrconf")){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
 #1: 000000008f3ed95c ((addr_chk_work).work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
 #2: 0000000016e40e94 (rtnl_mutex){+.+.}, at: addrconf_verify_work+0xa/0x20 net/ipv6/addrconf.c:4476
1 lock held by ksoftirqd/1/18:
4 locks held by kworker/u4:4/493:
 #0: 0000000039d04b70 ((wq_completion)"%s""netns"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
 #1: 00000000407e0c0e (net_cleanup_work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
 #2: 000000001864b374 (pernet_ops_rwsem){++++}, at: cleanup_net+0xa8/0x8b0 net/core/net_namespace.c:521
 #3: 0000000016e40e94 (rtnl_mutex){+.+.}, at: cangw_pernet_exit+0xe/0x20 net/can/gw.c:1032
1 lock held by khungtaskd/1571:
 #0: 0000000040d95a94 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441
3 locks held by khugepaged/1578:
1 lock held by systemd-journal/4687:
3 locks held by kworker/0:2/4695:
 #0: 000000006da7647a ((wq_completion)"events"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
 #1: 00000000c7b899c5 ((linkwatch_work).work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
 #2: 0000000016e40e94 (rtnl_mutex){+.+.}, at: linkwatch_event+0xb/0x60 net/core/link_watch.c:236
2 locks held by kworker/1:3/4716:
 #0: 00000000bf283164 ((wq_completion)"rcu_gp"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
 #1: 000000002008e0db ((work_completion)(&rew.rew_work)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
1 lock held by in:imklog/7799:
 #0: 00000000ee9417be (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767
3 locks held by kworker/0:4/9618:
 #0: 000000006da7647a ((wq_completion)"events"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
 #1: 000000009053de39 (deferred_process_work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
 #2: 0000000016e40e94 (rtnl_mutex){+.+.}, at: switchdev_deferred_process_work+0xa/0x20 net/switchdev/switchdev.c:150
2 locks held by syz-executor.0/12720:
 #0: 0000000016e40e94 (rtnl_mutex){+.+.}, at: tun_detach drivers/net/tun.c:759 [inline]
 #0: 0000000016e40e94 (rtnl_mutex){+.+.}, at: tun_chr_close+0x3a/0x180 drivers/net/tun.c:3323
 #1: 00000000e0096b69 (rcu_preempt_state.exp_mutex){+.+.}, at: exp_funnel_lock kernel/rcu/tree_exp.h:297 [inline]
 #1: 00000000e0096b69 (rcu_preempt_state.exp_mutex){+.+.}, at: _synchronize_rcu_expedited+0x4dc/0x6f0 kernel/rcu/tree_exp.h:667
2 locks held by kworker/u4:8/12748:
1 lock held by syz-executor.1/31683:
 #0: 0000000016e40e94 (rtnl_mutex){+.+.}, at: netdev_run_todo+0x719/0xab0 net/core/dev.c:9005
1 lock held by systemd-cgroups/31744:

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

NMI backtrace for cpu 0
CPU: 0 PID: 1571 Comm: khungtaskd Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/29/2022
Call Trace:
 __dump_stack lib/dump_stack.c:77 [inline]
 dump_stack+0x1fc/0x2ef lib/dump_stack.c:118
 nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x1a6/0x1f0 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline]
 watchdog+0x991/0xe60 kernel/hung_task.c:287
 kthread+0x33f/0x460 kernel/kthread.c:259
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 12748 Comm: kworker/u4:8 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/29/2022
Workqueue: bat_events batadv_nc_worker
RIP: 0010:get_current arch/x86/include/asm/current.h:15 [inline]
RIP: 0010:__sanitizer_cov_trace_pc+0x4/0x50 kernel/kcov.c:100
Code: e8 11 dd 35 00 e9 ab fe ff ff 4c 89 ef e8 04 dd 35 00 e9 23 fe ff ff 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 48 8b 34 24 <65> 48 8b 04 25 c0 df 01 00 65 8b 15 cc 59 9f 7e 81 e2 00 01 1f 00
RSP: 0018:ffff8880ba107998 EFLAGS: 00000097
RAX: 0000000080010101 RBX: ffff8880ba124cc0 RCX: ffffffff81542fbe
RDX: 0000000000000001 RSI: ffffffff81542fcd RDI: 0000000000000005
RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000007
R10: 0000000000000005 R11: ffffffff8c66505b R12: 0000000000000001
R13: ffff888099ac5250 R14: ffff8880ba124d40 R15: dffffc0000000000
FS:  0000000000000000(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f238798bf80 CR3: 000000009926c000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 cpumask_test_cpu include/linux/cpumask.h:344 [inline]
 trace_hrtimer_expire_exit include/trace/events/timer.h:274 [inline]
 __run_hrtimer kernel/time/hrtimer.c:1466 [inline]
 __hrtimer_run_queues+0x42d/0xe60 kernel/time/hrtimer.c:1527
 hrtimer_interrupt+0x326/0x9e0 kernel/time/hrtimer.c:1585
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1071 [inline]
 smp_apic_timer_interrupt+0x10c/0x550 arch/x86/kernel/apic/apic.c:1096
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:894
RIP: 0010:__sanitizer_cov_trace_const_cmp4+0xd/0x20 kernel/kcov.c:188
Code: 24 0f b7 d6 0f b7 f7 bf 03 00 00 00 e9 ec fe ff ff 66 66 2e 0f 1f 84 00 00 00 00 00 90 48 8b 0c 24 89 f2 89 fe bf 05 00 00 00 <e9> ce fe ff ff 66 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 48 8b 0c
RSP: 0018:ffff8880ba107c50 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: ffff888056151940 RBX: ffff888056151940 RCX: ffffffff8734e1f5
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000005
RBP: ffff88805cbf6680 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000005 R11: 0000000000000000 R12: 0000000000000000
R13: ffff88805cbf6680 R14: 000000000048104d R15: ffff88809f48aa80
 can_rcv_filter+0x65/0x980 net/can/af_can.c:626
 can_receive+0x2f0/0x530 net/can/af_can.c:707
 canfd_rcv+0x12f/0x1d0 net/can/af_can.c:764
 __netif_receive_skb_one_core+0x114/0x180 net/core/dev.c:4954
 __netif_receive_skb+0x27/0x1c0 net/core/dev.c:5066
 process_backlog+0x241/0x700 net/core/dev.c:5849
 napi_poll net/core/dev.c:6280 [inline]
 net_rx_action+0x4ac/0xfb0 net/core/dev.c:6346
 __do_softirq+0x265/0x980 kernel/softirq.c:292
 do_softirq_own_stack+0x2a/0x40 arch/x86/entry/entry_64.S:1092
 </IRQ>
 do_softirq.part.0+0x160/0x1c0 kernel/softirq.c:336
 do_softirq kernel/softirq.c:328 [inline]
 __local_bh_enable_ip+0x20e/0x270 kernel/softirq.c:189
 spin_unlock_bh include/linux/spinlock.h:374 [inline]
 batadv_nc_purge_paths+0x22d/0x310 net/batman-adv/network-coding.c:482
 batadv_nc_worker+0x6fa/0xd50 net/batman-adv/network-coding.c:731
 process_one_work+0x864/0x1570 kernel/workqueue.c:2153
 worker_thread+0x64c/0x1130 kernel/workqueue.c:2296
 kthread+0x33f/0x460 kernel/kthread.c:259
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415

Crashes (6):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets Title
ci2-linux-4-19 2022/07/18 03:06 linux-4.19.y 3f8a27f9e27b 95cb00d1 .config console log report info INFO: task hung in cangw_pernet_exit
ci2-linux-4-19 2022/04/24 12:56 linux-4.19.y 3f8a27f9e27b 131df97d .config console log report info INFO: task hung in cangw_pernet_exit
ci2-linux-4-19 2022/03/13 01:44 linux-4.19.y 3f8a27f9e27b 9e8eaa75 .config console log report info INFO: task hung in cangw_pernet_exit
ci2-linux-4-19 2022/03/10 11:38 linux-4.19.y 3f8a27f9e27b 9e8eaa75 .config console log report info INFO: task hung in cangw_pernet_exit
ci2-linux-4-19 2021/12/05 20:48 linux-4.19.y 3f8a27f9e27b a617004c .config console log report info INFO: task hung in cangw_pernet_exit
ci2-linux-4-19 2021/11/24 20:11 linux-4.19.y 3f8a27f9e27b 545ab074 .config console log report info INFO: task hung in cangw_pernet_exit
* Struck through repros no longer work on HEAD.