syzbot


INFO: task hung in sock_ioctl (2)

Status: auto-closed as invalid on 2021/12/21 20:14
Reported-by: syzbot+@syzkaller.appspotmail.com
First crash: 431d, last: 431d
similar bugs (4):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-4.14 INFO: task hung in sock_ioctl 1 960d 960d 0/1 auto-closed as invalid on 2020/08/10 12:43
upstream INFO: task hung in sock_ioctl 9 1414d 1771d 0/24 auto-closed as invalid on 2019/07/13 13:17
linux-4.14 INFO: task hung in sock_ioctl (2) 1 660d 660d 0/1 auto-closed as invalid on 2021/06/05 18:05
upstream INFO: task hung in rtnetlink_rcv_msg C inconclusive inconclusive 387 6d15h 1374d 0/24 upstream: reported C repro on 2019/02/22 17:00

Sample crash report:
INFO: task syz-executor.0:1639 blocked for more than 143 seconds.
      Not tainted 5.15.0-rc2-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0  state:D stack:27664 pid: 1639 ppid:  6538 flags:0x00004004
Call Trace:
 context_switch kernel/sched/core.c:4940 [inline]
 __schedule+0xc8d/0x1270 kernel/sched/core.c:6287
 schedule+0x14b/0x210 kernel/sched/core.c:6366
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6425
 __mutex_lock_common+0xdff/0x2550 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x1a/0x20 kernel/locking/mutex.c:743
 br_ioctl_call net/socket.c:1090 [inline]
 sock_ioctl+0x2b9/0x990 net/socket.c:1189
 vfs_ioctl fs/ioctl.c:51 [inline]
 __do_sys_ioctl fs/ioctl.c:874 [inline]
 __se_sys_ioctl+0xfb/0x170 fs/ioctl.c:860
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x44/0xd0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7f9f67565709
RSP: 002b:00007f9f64abb188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007f9f6766a020 RCX: 00007f9f67565709
RDX: 0000000020000440 RSI: 00000000000089a0 RDI: 0000000000000005
RBP: 00007f9f675bfcb4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fff818f7eaf R14: 00007f9f64abb300 R15: 0000000000022000
INFO: task syz-executor.5:1649 blocked for more than 143 seconds.
      Not tainted 5.15.0-rc2-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.5  state:D stack:27920 pid: 1649 ppid:  6940 flags:0x00004004
Call Trace:
 context_switch kernel/sched/core.c:4940 [inline]
 __schedule+0xc8d/0x1270 kernel/sched/core.c:6287
 schedule+0x14b/0x210 kernel/sched/core.c:6366
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6425
 __mutex_lock_common+0xdff/0x2550 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x1a/0x20 kernel/locking/mutex.c:743
 br_ioctl_call net/socket.c:1090 [inline]
 sock_ioctl+0x2b9/0x990 net/socket.c:1189
 vfs_ioctl fs/ioctl.c:51 [inline]
 __do_sys_ioctl fs/ioctl.c:874 [inline]
 __se_sys_ioctl+0xfb/0x170 fs/ioctl.c:860
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x44/0xd0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7f7b84c75709
RSP: 002b:00007f7b821aa188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007f7b84d7a0e0 RCX: 00007f7b84c75709
RDX: 0000000020000440 RSI: 00000000000089a0 RDI: 0000000000000005
RBP: 00007f7b84ccfcb4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fff6dbeceff R14: 00007f7b821aa300 R15: 0000000000022000
INFO: task syz-executor.3:1652 blocked for more than 143 seconds.
      Not tainted 5.15.0-rc2-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.3  state:D stack:28688 pid: 1652 ppid:  1270 flags:0x00004004
Call Trace:
 context_switch kernel/sched/core.c:4940 [inline]
 __schedule+0xc8d/0x1270 kernel/sched/core.c:6287
 schedule+0x14b/0x210 kernel/sched/core.c:6366
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6425
 __mutex_lock_common+0xdff/0x2550 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x1a/0x20 kernel/locking/mutex.c:743
 br_ioctl_call net/socket.c:1090 [inline]
 sock_ioctl+0x2b9/0x990 net/socket.c:1189
 vfs_ioctl fs/ioctl.c:51 [inline]
 __do_sys_ioctl fs/ioctl.c:874 [inline]
 __se_sys_ioctl+0xfb/0x170 fs/ioctl.c:860
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x44/0xd0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7fa9ce6d8709
RSP: 002b:00007fa9cbc0d188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007fa9ce7dd0e0 RCX: 00007fa9ce6d8709
RDX: 0000000020000440 RSI: 00000000000089a0 RDI: 0000000000000005
RBP: 00007fa9ce732cb4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffdaf2c409f R14: 00007fa9cbc0d300 R15: 0000000000022000
INFO: task syz-executor.4:1662 blocked for more than 144 seconds.
      Not tainted 5.15.0-rc2-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.4  state:D stack:24816 pid: 1662 ppid:     1 flags:0x00004004
Call Trace:
 context_switch kernel/sched/core.c:4940 [inline]
 __schedule+0xc8d/0x1270 kernel/sched/core.c:6287
 schedule+0x14b/0x210 kernel/sched/core.c:6366
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6425
 __mutex_lock_common+0xdff/0x2550 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x1a/0x20 kernel/locking/mutex.c:743
 rtnl_lock net/core/rtnetlink.c:72 [inline]
 rtnetlink_rcv_msg+0x8ed/0xe60 net/core/rtnetlink.c:5569
 netlink_rcv_skb+0x200/0x470 net/netlink/af_netlink.c:2504
 netlink_unicast_kernel net/netlink/af_netlink.c:1314 [inline]
 netlink_unicast+0x814/0x9f0 net/netlink/af_netlink.c:1340
 netlink_sendmsg+0xa29/0xe50 net/netlink/af_netlink.c:1929
 sock_sendmsg_nosec net/socket.c:704 [inline]
 sock_sendmsg net/socket.c:724 [inline]
 __sys_sendto+0x560/0x720 net/socket.c:2036
 __do_sys_sendto net/socket.c:2048 [inline]
 __se_sys_sendto net/socket.c:2044 [inline]
 __x64_sys_sendto+0xda/0xf0 net/socket.c:2044
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x44/0xd0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7f8e9928969c
RSP: 002b:00007ffcb50e38f0 EFLAGS: 00000293 ORIG_RAX: 000000000000002c
RAX: ffffffffffffffda RBX: 00007f8e9a313320 RCX: 00007f8e9928969c
RDX: 0000000000000028 RSI: 00007f8e9a313370 RDI: 0000000000000003
RBP: 0000000000000000 R08: 00007ffcb50e3944 R09: 000000000000000c
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000
R13: 00007f8e9a313370 R14: 0000000000000003 R15: 0000000000000000

Showing all locks held in the system:
1 lock held by khungtaskd/27:
 #0: ffffffff8c91c200 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x0/0x30
3 locks held by kworker/u4:3/155:
 #0: ffff8880111b6138 ((wq_completion)cfg80211){+.+.}-{0:0}, at: process_one_work+0x7ca/0x1140
 #1: ffffc900012cfd20 ((work_completion)(&(&rdev->dfs_update_channels_wk)->work)){+.+.}-{0:0}, at: process_one_work+0x808/0x1140 kernel/workqueue.c:2272
 #2: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: cfg80211_dfs_channels_update_work+0xbc/0x660 net/wireless/mlme.c:842
1 lock held by in:imklog/6233:
 #0: ffff88801b6aa5f0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x24e/0x2f0 fs/file.c:990
4 locks held by kworker/u4:11/10650:
 #0: ffff8880124d2938 ((wq_completion)netns){+.+.}-{0:0}, at: process_one_work+0x7ca/0x1140
 #1: ffffc9000ae97d20 (net_cleanup_work){+.+.}-{0:0}, at: process_one_work+0x808/0x1140 kernel/workqueue.c:2272
 #2: ffffffff8d94fc70 (pernet_ops_rwsem){++++}-{3:3}, at: cleanup_net+0xf0/0xc50 net/core/net_namespace.c:553
 #3: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: fib6_rules_net_exit+0xe/0x50 net/ipv6/fib6_rules.c:496
3 locks held by kworker/0:10/30090:
 #0: ffff888011064d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x7ca/0x1140
 #1: ffffc90004827d20 ((linkwatch_work).work){+.+.}-{0:0}, at: process_one_work+0x808/0x1140 kernel/workqueue.c:2272
 #2: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: linkwatch_event+0xa/0x50 net/core/link_watch.c:251
3 locks held by kworker/0:11/30091:
 #0: ffff888011065d38 ((wq_completion)events_power_efficient){+.+.}-{0:0}, at: process_one_work+0x7ca/0x1140
 #1: ffffc90004887d20 ((reg_check_chans).work){+.+.}-{0:0}, at: process_one_work+0x808/0x1140 kernel/workqueue.c:2272
 #2: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: reg_check_chans_work+0x7a/0x800 net/wireless/reg.c:2423
2 locks held by kworker/0:12/30092:
3 locks held by kworker/0:13/30093:
 #0: ffff888011064d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x7ca/0x1140
 #1: ffffc90004997d20 (deferred_process_work){+.+.}-{0:0}, at: process_one_work+0x808/0x1140 kernel/workqueue.c:2272
 #2: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: switchdev_deferred_process_work+0xa/0x20 net/switchdev/switchdev.c:74
3 locks held by kworker/0:21/30101:
 #0: ffff88802634fd38 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0x7ca/0x1140
 #1: ffffc90004c07d20 ((work_completion)(&(&ifa->dad_work)->work)){+.+.}-{0:0}, at: process_one_work+0x808/0x1140 kernel/workqueue.c:2272
 #2: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: addrconf_dad_work+0xdb/0x1d10 net/ipv6/addrconf.c:4047
3 locks held by kworker/1:11/30699:
 #0: ffff88802634fd38 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0x7ca/0x1140
 #1: ffffc90016077d20 ((work_completion)(&(&ifa->dad_work)->work)){+.+.}-{0:0}, at: process_one_work+0x808/0x1140 kernel/workqueue.c:2272
 #2: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: addrconf_dad_work+0xdb/0x1d10 net/ipv6/addrconf.c:4047
1 lock held by syz-executor.2/1642:
 #0: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: tun_detach drivers/net/tun.c:684 [inline]
 #0: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: tun_chr_close+0x3c/0x130 drivers/net/tun.c:3397
1 lock held by syz-executor.0/1639:
 #0: ffffffff8d941c08 (br_ioctl_mutex){+.+.}-{3:3}, at: br_ioctl_call net/socket.c:1090 [inline]
 #0: ffffffff8d941c08 (br_ioctl_mutex){+.+.}-{3:3}, at: sock_ioctl+0x2b9/0x990 net/socket.c:1189
1 lock held by syz-executor.5/1649:
 #0: ffffffff8d941c08 (br_ioctl_mutex){+.+.}-{3:3}, at: br_ioctl_call net/socket.c:1090 [inline]
 #0: ffffffff8d941c08 (br_ioctl_mutex){+.+.}-{3:3}, at: sock_ioctl+0x2b9/0x990 net/socket.c:1189
2 locks held by syz-executor.3/1637:
 #0: ffffffff8d941c08 (br_ioctl_mutex){+.+.}-{3:3}, at: br_ioctl_call net/socket.c:1090 [inline]
 #0: ffffffff8d941c08 (br_ioctl_mutex){+.+.}-{3:3}, at: sock_ioctl+0x2b9/0x990 net/socket.c:1189
 #1: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: netdev_run_todo+0xafa/0xcf0 net/core/dev.c:10594
1 lock held by syz-executor.3/1652:
 #0: ffffffff8d941c08 (br_ioctl_mutex){+.+.}-{3:3}, at: br_ioctl_call net/socket.c:1090 [inline]
 #0: ffffffff8d941c08 (br_ioctl_mutex){+.+.}-{3:3}, at: sock_ioctl+0x2b9/0x990 net/socket.c:1189
2 locks held by syz-executor.1/1660:
 #0: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: rtnl_lock net/core/rtnetlink.c:72 [inline]
 #0: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x8ed/0xe60 net/core/rtnetlink.c:5569
 #1: ffffffff8c9209e8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: exp_funnel_lock kernel/rcu/tree_exp.h:290 [inline]
 #1: ffffffff8c9209e8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x277/0x750 kernel/rcu/tree_exp.h:837
1 lock held by syz-executor.4/1662:
 #0: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: rtnl_lock net/core/rtnetlink.c:72 [inline]
 #0: ffffffff8d95b8c8 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x8ed/0xe60 net/core/rtnetlink.c:5569

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

NMI backtrace for cpu 1
CPU: 1 PID: 27 Comm: khungtaskd Not tainted 5.15.0-rc2-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0x1dc/0x2d8 lib/dump_stack.c:106
 nmi_cpu_backtrace+0x45f/0x490 lib/nmi_backtrace.c:105
 nmi_trigger_cpumask_backtrace+0x16a/0x280 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:210 [inline]
 watchdog+0xc54/0xca0 kernel/hung_task.c:295
 kthread+0x453/0x480 kernel/kthread.c:319
 ret_from_fork+0x1f/0x30
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 23644 Comm: kworker/u4:4 Not tainted 5.15.0-rc2-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: bat_events batadv_iv_send_outstanding_bat_ogm_packet
RIP: 0010:kasan_check_range+0x222/0x2f0 mm/kasan/generic.c:189
Code: 03 4d 89 cf 49 8d 5e 07 4d 85 f6 49 0f 49 de 48 83 e3 f8 49 29 de 74 11 45 0f b6 1f 45 84 db 75 64 49 ff c7 49 ff ce 75 ef 5b <41> 5c 41 5d 41 5e 41 5f c3 45 84 db 75 50 45 8a 59 01 45 84 db 0f
RSP: 0018:ffffc9000b4af5f8 EFLAGS: 00000056
RAX: 1ffffffff1871201 RBX: ffff8880b9c32508 RCX: ffffffff816cf468
RDX: 0000000000000000 RSI: 0000000000000004 RDI: ffff8880b9c32508
RBP: ffffc9000b4af6c8 R08: dffffc0000000000 R09: ffffed10173864a2
R10: ffffed10173864a2 R11: 0000000000000000 R12: 1ffff110173864a1
R13: dffffc0000000001 R14: 0000000000000001 R15: dffffc0000000000
FS:  0000000000000000(0000) GS:ffff8880b9c00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f380b399000 CR3: 0000000071b85000 CR4: 00000000001506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 instrument_atomic_read include/linux/instrumented.h:71 [inline]
 atomic_read include/linux/atomic/atomic-instrumented.h:27 [inline]
 rcu_dynticks_curr_cpu_in_eqs kernel/rcu/tree.c:330 [inline]
 rcu_is_watching+0x58/0xb0 kernel/rcu/tree.c:1121
 rcu_read_lock_held_common kernel/rcu/update.c:108 [inline]
 rcu_read_lock_sched_held+0x6b/0x130 kernel/rcu/update.c:123
 trace_lock_acquire+0x59/0x190 include/trace/events/lock.h:13
 lock_acquire+0xa5/0x4d0 kernel/locking/lockdep.c:5596
 rcu_lock_acquire+0x20/0x30 include/linux/rcupdate.h:267
 rcu_read_lock include/linux/rcupdate.h:687 [inline]
 cgroup_account_cputime include/linux/cgroup.h:794 [inline]
 update_curr+0x3e0/0xcb0 kernel/sched/fair.c:853
 enqueue_entity+0xac/0x11a0 kernel/sched/fair.c:4293
 enqueue_task_fair+0x359/0x1bb0 kernel/sched/fair.c:5616
 enqueue_task kernel/sched/core.c:1976 [inline]
 activate_task+0x116/0x300 kernel/sched/core.c:2001
 ttwu_do_activate+0x142/0x2a0 kernel/sched/core.c:3577
 ttwu_queue kernel/sched/core.c:3774 [inline]
 try_to_wake_up+0x8d4/0xef0 kernel/sched/core.c:4097
 wake_up_worker kernel/workqueue.c:855 [inline]
 process_one_work+0x706/0x1140 kernel/workqueue.c:2260
 worker_thread+0xac1/0x1320 kernel/workqueue.c:2444
 kthread+0x453/0x480 kernel/kthread.c:319
 ret_from_fork+0x1f/0x30
----------------
Code disassembly (best guess):
   0:	03 4d 89             	add    -0x77(%rbp),%ecx
   3:	cf                   	iret
   4:	49 8d 5e 07          	lea    0x7(%r14),%rbx
   8:	4d 85 f6             	test   %r14,%r14
   b:	49 0f 49 de          	cmovns %r14,%rbx
   f:	48 83 e3 f8          	and    $0xfffffffffffffff8,%rbx
  13:	49 29 de             	sub    %rbx,%r14
  16:	74 11                	je     0x29
  18:	45 0f b6 1f          	movzbl (%r15),%r11d
  1c:	45 84 db             	test   %r11b,%r11b
  1f:	75 64                	jne    0x85
  21:	49 ff c7             	inc    %r15
  24:	49 ff ce             	dec    %r14
  27:	75 ef                	jne    0x18
  29:	5b                   	pop    %rbx
* 2a:	41 5c                	pop    %r12 <-- trapping instruction
  2c:	41 5d                	pop    %r13
  2e:	41 5e                	pop    %r14
  30:	41 5f                	pop    %r15
  32:	c3                   	retq
  33:	45 84 db             	test   %r11b,%r11b
  36:	75 50                	jne    0x88
  38:	45 8a 59 01          	mov    0x1(%r9),%r11b
  3c:	45 84 db             	test   %r11b,%r11b
  3f:	0f                   	.byte 0xf

Crashes (1):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci-upstream-kasan-gce-smack-root 2021/09/22 20:06 upstream cf1d2c3e7e2f 8cac236e .config log report info INFO: task hung in sock_ioctl
* Struck through repros no longer work on HEAD.