syzbot


INFO: task hung in rtnl_newlink

Status: auto-closed as invalid on 2021/07/01 14:20
Reported-by: syzbot+a9f15700036a4adb141c@syzkaller.appspotmail.com
First crash: 1143d, last: 1143d

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 syz-executor.1:18283 blocked for more than 140 seconds.
      Not tainted 4.19.177-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.1  D23672 18283   8107 0x00000004
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_timeout+0x92d/0xfe0 kernel/time/timer.c:1794
 do_wait_for_common kernel/sched/completion.c:83 [inline]
 __wait_for_common kernel/sched/completion.c:104 [inline]
 wait_for_common+0x29c/0x470 kernel/sched/completion.c:115
 __flush_work+0x4bb/0x8b0 kernel/workqueue.c:2925
 flush_all_backlogs net/core/dev.c:5283 [inline]
 rollback_registered_many+0x4b1/0xe70 net/core/dev.c:8173
 unregister_netdevice_many.part.0+0x1a/0x300 net/core/dev.c:9316
 unregister_netdevice_many+0x36/0x50 net/core/dev.c:9315
 rtnl_newlink+0x12fd/0x15b0 net/core/rtnetlink.c:3185
 rtnetlink_rcv_msg+0x453/0xb80 net/core/rtnetlink.c:4778
 netlink_rcv_skb+0x160/0x440 net/netlink/af_netlink.c:2455
 netlink_unicast_kernel net/netlink/af_netlink.c:1318 [inline]
 netlink_unicast+0x4d5/0x690 net/netlink/af_netlink.c:1344
 netlink_sendmsg+0x6bb/0xc40 net/netlink/af_netlink.c:1909
 sock_sendmsg_nosec net/socket.c:622 [inline]
 sock_sendmsg+0xc3/0x120 net/socket.c:632
 ___sys_sendmsg+0x7bb/0x8e0 net/socket.c:2115
 __sys_sendmsg net/socket.c:2153 [inline]
 __do_sys_sendmsg net/socket.c:2162 [inline]
 __se_sys_sendmsg net/socket.c:2160 [inline]
 __x64_sys_sendmsg+0x132/0x220 net/socket.c:2160
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x465ef9
Code: Bad RIP value.
RSP: 002b:00007fedf0fca188 EFLAGS: 00000246 ORIG_RAX: 000000000000002e
RAX: ffffffffffffffda RBX: 000000000056bf60 RCX: 0000000000465ef9
RDX: 0000000000000000 RSI: 0000000020000040 RDI: 0000000000000006
RBP: 00000000004bfa34 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007ffd72684ebf R14: 00007fedf0fca300 R15: 0000000000022000

Showing all locks held in the system:
3 locks held by kworker/u4:0/7:
 #0: 000000006b0bb29f ((wq_completion)"%s""netns"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2123
 #1: 0000000055ca7e1b (net_cleanup_work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2127
 #2: 000000003c661e0c (pernet_ops_rwsem){++++}, at: cleanup_net+0xa8/0x8b0 net/core/net_namespace.c:520
1 lock held by khungtaskd/1567:
 #0: 0000000035db8b8a (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440
1 lock held by systemd-journal/4680:
1 lock held by in:imklog/7793:
 #0: 000000001bf05aa9 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767
3 locks held by rs:main Q:Reg/7794:
 #0: 000000008a355eee (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1824 [inline]
 #0: 000000008a355eee (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
 #1: 0000000035db8b8a (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline]
 #1: 0000000035db8b8a (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857
 #2: 00000000c13196e9 (&sb->s_type->i_mutex_key#10){+.+.}, at: inode_trylock include/linux/fs.h:768 [inline]
 #2: 00000000c13196e9 (&sb->s_type->i_mutex_key#10){+.+.}, at: ext4_file_write_iter+0x21f/0xf20 fs/ext4/file.c:238
6 locks held by kworker/u4:7/9549:
1 lock held by syz-executor.3/17668:
2 locks held by syz-executor.1/18283:
 #0: 000000007bcb1711 (rtnl_mutex){+.+.}, at: rtnl_lock net/core/rtnetlink.c:77 [inline]
 #0: 000000007bcb1711 (rtnl_mutex){+.+.}, at: rtnetlink_rcv_msg+0x3fe/0xb80 net/core/rtnetlink.c:4775
 #1: 0000000063dd7cc8 (cpu_hotplug_lock.rw_sem){++++}, at: get_online_cpus include/linux/cpu.h:137 [inline]
 #1: 0000000063dd7cc8 (cpu_hotplug_lock.rw_sem){++++}, at: flush_all_backlogs net/core/dev.c:5276 [inline]
 #1: 0000000063dd7cc8 (cpu_hotplug_lock.rw_sem){++++}, at: rollback_registered_many+0x3a8/0xe70 net/core/dev.c:8173

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

NMI backtrace for cpu 0
CPU: 0 PID: 1567 Comm: khungtaskd Not tainted 4.19.177-syzkaller #0
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+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: 17668 Comm: syz-executor.3 Not tainted 4.19.177-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:rcu_read_lock_sched_held+0xd3/0x1d0 kernel/rcu/update.c:115
Code: c0 03 38 d0 7c 08 84 d2 0f 85 dd 00 00 00 8b 95 84 08 00 00 85 d2 75 b4 e8 0a da 00 00 84 c0 0f 84 88 00 00 00 e8 3d 8c 00 00 <84> c0 74 7f 48 b8 00 00 00 00 00 fc ff df 48 89 da 83 e3 07 48 c1
RSP: 0000:ffff8880610bfb50 EFLAGS: 00000086
RAX: 0000000000000001 RBX: ffffffff8a45cd80 RCX: 0000000000000000
RDX: 0000000000000004 RSI: ffffffff8375a0d1 RDI: 0000000000000001
RBP: ffff8880b4ca4580 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000005 R11: 00000000d44a52b3 R12: 0000000000000000
R13: 000000000000000b R14: 0000000000000400 R15: ffff8880b4ca4c88
FS:  00007f36e1fac700(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fffec2d6f4f CR3: 00000000962a9000 CR4: 00000000001426e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 trace_signal_generate include/trace/events/signal.h:51 [inline]
 __send_signal+0x122d/0x15c0 kernel/signal.c:1199
 specific_send_sig_info kernel/signal.c:1258 [inline]
 force_sig_info+0x262/0x3b0 kernel/signal.c:1310
 force_sig_info_fault.constprop.0+0x1e6/0x320 arch/x86/mm/fault.c:226
 __bad_area_nosemaphore+0x2c1/0x400 arch/x86/mm/fault.c:910
 __bad_area arch/x86/mm/fault.c:944 [inline]
 bad_area_access_error+0x25c/0x4a0 arch/x86/mm/fault.c:983
 __do_page_fault+0xb09/0xd60 arch/x86/mm/fault.c:1392
 page_fault+0x1e/0x30 arch/x86/entry/entry_64.S:1205
RIP: 0033:0x7fffec2d6f4f
Code: 00 00 00 60 bf 56 00 00 00 00 00 eb 8c 03 00 00 00 00 00 12 1a 41 00 00 00 00 00 01 80 ad fb 00 00 00 00 70 72 2d ec ff 7f 00 <01> 60 bf 56 00 00 00 00 00 60 bf 56 00 00 00 00 00 c0 80 56 00 00
RSP: 002b:00007f36e1fac220 EFLAGS: 00010206
RAX: 0000000000000000 RBX: 000000000056bf68 RCX: 00000000004c086d
RDX: 00000000000566d6 RSI: 0000000000001f20 RDI: 00000000004d056a
RBP: 000000000056bf60 R08: 0000000000000000 R09: 000000000000000e
R10: 0016eb4319873f8f R11: 0000000000000001 R12: 000000000056bf6c
R13: 00007fffec2d6f4f R14: 00007f36e1fac300 R15: 0000000000022000

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2021/03/03 14:20 linux-4.19.y 2d19be4653f5 06ed56cd .config console log report info ci2-linux-4-19 INFO: task hung in rtnl_newlink
* Struck through repros no longer work on HEAD.