syzbot


INFO: task hung in rxrpc_release (3)

Status: upstream: reported on 2022/10/17 23:49
Reported-by: syzbot+54838e5c2f05a6424888@syzkaller.appspotmail.com
First crash: 554d, last: 428d
Similar bugs (7)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-6.1 INFO: task hung in rxrpc_release 1 368d 368d 0/3 auto-obsoleted due to no activity on 2023/08/19 19:10
linux-4.19 INFO: task hung in rxrpc_release (2) 3 859d 884d 0/1 auto-closed as invalid on 2022/04/15 12:04
linux-5.15 INFO: task hung in rxrpc_release 1 387d 387d 0/3 auto-obsoleted due to no activity on 2023/07/31 18:39
upstream INFO: task hung in rxrpc_release (2) net afs 1 1412d 1412d 0/26 auto-closed as invalid on 2020/09/09 16:08
upstream INFO: task hung in rxrpc_release (3) afs net syz unreliable 7 79d 80d 0/26 upstream: reported syz repro on 2024/02/03 15:38
upstream INFO: task hung in rxrpc_release afs net 1 1544d 1544d 0/26 auto-closed as invalid on 2020/05/30 15:47
linux-4.19 INFO: task hung in rxrpc_release 1 1569d 1569d 0/1 auto-closed as invalid on 2020/05/05 21:00

Sample crash report:
Bluetooth: hci7: command 0x041b tx timeout
Bluetooth: hci7: command 0x040f tx timeout
Bluetooth: hci7: command 0x0419 tx timeout
ieee802154 phy0 wpan0: encryption failed: -22
ieee802154 phy1 wpan1: encryption failed: -22
INFO: task syz-executor.1:13966 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.
syz-executor.1  D28648 13966   8184 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_workqueue+0x40b/0x13e0 kernel/workqueue.c:2713
 rxrpc_release_sock net/rxrpc/af_rxrpc.c:906 [inline]
 rxrpc_release+0x25b/0x530 net/rxrpc/af_rxrpc.c:936
 __sock_release+0xcd/0x2a0 net/socket.c:599
 sock_close+0x15/0x20 net/socket.c:1214
 __fput+0x2ce/0x890 fs/file_table.c:278
 task_work_run+0x148/0x1c0 kernel/task_work.c:113
 tracehook_notify_resume include/linux/tracehook.h:193 [inline]
 exit_to_usermode_loop+0x251/0x2a0 arch/x86/entry/common.c:167
 prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline]
 syscall_return_slowpath arch/x86/entry/common.c:271 [inline]
 do_syscall_64+0x538/0x620 arch/x86/entry/common.c:296
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7f007047efab
Code: Bad RIP value.
RSP: 002b:00007ffc6ac872e0 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000007 RCX: 00007f007047efab
RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000006
RBP: 00007f00705ee980 R08: 0000000000000000 R09: 0000003fd0d64e5c
R10: 0000000000000000 R11: 0000000000000293 R12: 000000000003c3f5
R13: 00007ffc6ac873e0 R14: 00007f00705ed2c0 R15: 0000000000000032

Showing all locks held in the system:
4 locks held by kworker/u4:2/80:
 #0: 0000000017891c52 ((wq_completion)"writeback"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
 #1: 00000000f21ea624 ((work_completion)(&(&wb->dwork)->work)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
 #2: 000000001807c9c1 (&type->s_umount_key#54){.+.+}, at: trylock_super+0x1d/0x100 fs/super.c:412
 #3: 000000004b818188 (&sbi->gc_mutex){+.+.}, at: f2fs_balance_fs+0x709/0xd80 fs/f2fs/segment.c:512
1 lock held by khungtaskd/1571:
 #0: 00000000eede8e46 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441
3 locks held by kworker/0:2/4695:
 #0: 000000005c98b292 ((wq_completion)"%s"("ipv6_addrconf")){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
 #1: 000000009a0d186b ((addr_chk_work).work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
 #2: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: addrconf_verify_work+0xa/0x20 net/ipv6/addrconf.c:4476
1 lock held by in:imklog/7869:
 #0: 000000004934ee7c (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767
3 locks held by syz-executor.1/13091:
1 lock held by syz-executor.1/13966:
 #0: 0000000057bc0a30 (&sb->s_type->i_mutex_key#13){+.+.}, at: inode_lock include/linux/fs.h:748 [inline]
 #0: 0000000057bc0a30 (&sb->s_type->i_mutex_key#13){+.+.}, at: __sock_release+0x86/0x2a0 net/socket.c:598
2 locks held by syz-executor.4/14049:
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: rtnl_lock net/core/rtnetlink.c:77 [inline]
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: rtnetlink_rcv_msg+0x3fe/0xb80 net/core/rtnetlink.c:4779
 #1: 00000000ec67b542 (cpu_hotplug_lock.rw_sem){++++}, at: get_online_cpus include/linux/cpu.h:137 [inline]
 #1: 00000000ec67b542 (cpu_hotplug_lock.rw_sem){++++}, at: flush_all_backlogs net/core/dev.c:5276 [inline]
 #1: 00000000ec67b542 (cpu_hotplug_lock.rw_sem){++++}, at: rollback_registered_many+0x3a8/0xe70 net/core/dev.c:8181
1 lock held by syz-executor.0/14091:
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: packet_mc_add net/packet/af_packet.c:3571 [inline]
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: packet_setsockopt+0x184a/0x2cc0 net/packet/af_packet.c:3692
1 lock held by syz-executor.0/14097:
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: packet_mc_add net/packet/af_packet.c:3571 [inline]
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: packet_setsockopt+0x184a/0x2cc0 net/packet/af_packet.c:3692
1 lock held by syz-executor.0/14103:
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: packet_mc_add net/packet/af_packet.c:3571 [inline]
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: packet_setsockopt+0x184a/0x2cc0 net/packet/af_packet.c:3692
1 lock held by syz-executor.1/14115:
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: rtnl_lock net/core/rtnetlink.c:77 [inline]
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: rtnetlink_rcv_msg+0x3fe/0xb80 net/core/rtnetlink.c:4779
1 lock held by syz-executor.1/14127:
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: rtnl_lock net/core/rtnetlink.c:77 [inline]
 #0: 000000004c4c60d0 (rtnl_mutex){+.+.}, at: rtnetlink_rcv_msg+0x3fe/0xb80 net/core/rtnetlink.c:4779

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

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 01/21/2023
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: 13091 Comm: syz-executor.1 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023
RIP: 0010:task_irq_context kernel/locking/lockdep.c:3039 [inline]
RIP: 0010:__lock_acquire+0x468/0x3ff0 kernel/locking/lockdep.c:3361
Code: 54 08 00 00 49 8d bd 74 08 00 00 ba 02 00 00 00 48 89 f9 85 c0 0f 45 c2 48 c1 e9 03 48 ba 00 00 00 00 00 fc ff df 0f b6 0c 11 <48> 89 fa 83 e2 07 83 c2 03 38 ca 7c 08 84 c9 0f 85 ec 26 00 00 41
RSP: 0018:ffff88804a546420 EFLAGS: 00000803
RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
RDX: dffffc0000000000 RSI: 1ffff1101396c9ba RDI: ffff88809cb64d74
RBP: 0000000000000001 R08: 000000000000086e R09: 0000000000000002
R10: ffff88809cb64dd8 R11: 0000000000000000 R12: 0000000000000000
R13: ffff88809cb64500 R14: ffff88809cb64df0 R15: 0000000000000001
FS:  0000000020000240(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f608ebed5f0 CR3: 0000000097dbf000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 lock_acquire+0x170/0x3c0 kernel/locking/lockdep.c:3908
 down_read+0x36/0x80 kernel/locking/rwsem.c:24
 check_valid_map fs/f2fs/gc.c:454 [inline]
 gc_data_segment fs/f2fs/gc.c:929 [inline]
 do_garbage_collect fs/f2fs/gc.c:1109 [inline]
 f2fs_gc+0x1cf3/0x8c90 fs/f2fs/gc.c:1196
 f2fs_balance_fs+0x71a/0xd80 fs/f2fs/segment.c:513
 __write_data_page+0xab8/0x22d0 fs/f2fs/data.c:1975
 f2fs_write_cache_pages+0x96e/0x13e0 fs/f2fs/data.c:2107
 __f2fs_write_data_pages fs/f2fs/data.c:2217 [inline]
 f2fs_write_data_pages+0xcc3/0x1060 fs/f2fs/data.c:2244
 do_writepages+0xe5/0x290 mm/page-writeback.c:2344
 __filemap_fdatawrite_range+0x27d/0x350 mm/filemap.c:446
 file_write_and_wait_range+0x93/0x100 mm/filemap.c:776
 f2fs_do_sync_file+0x2eb/0x2550 fs/f2fs/file.c:228
 f2fs_sync_file+0x136/0x190 fs/f2fs/file.c:334
 vfs_fsync_range+0x13a/0x220 fs/sync.c:197
 generic_write_sync include/linux/fs.h:2750 [inline]
 f2fs_file_write_iter+0x64b/0xbe0 fs/f2fs/file.c:3044
 call_write_iter include/linux/fs.h:1821 [inline]
 new_sync_write fs/read_write.c:474 [inline]
 __vfs_write+0x51b/0x770 fs/read_write.c:487
 __kernel_write+0x109/0x370 fs/read_write.c:506
 dump_emit+0x183/0x300 fs/coredump.c:801
 elf_core_dump+0x33c0/0x4c10 fs/binfmt_elf.c:2392
 do_coredump+0x1d4e/0x2d60 fs/coredump.c:765
 get_signal+0xed9/0x1f70 kernel/signal.c:2583
 do_signal+0x8f/0x1670 arch/x86/kernel/signal.c:799
 exit_to_usermode_loop+0x204/0x2a0 arch/x86/entry/common.c:163
 prepare_exit_to_usermode+0x277/0x2d0 arch/x86/entry/common.c:198
 retint_user+0x8/0x18
RIP: 0033:0x7f00704cd101
Code: c4 28 c3 e8 f1 19 00 00 90 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 48 3d 01 f0 ff ff 73 01 <c3> 48 c7 c1 b8 ff ff ff f7 d8 64 89 01 48 83 c8 ff c3 66 2e 0f 1f
RSP: 002b:0000000020000030 EFLAGS: 00010217
RAX: 0000000000000000 RBX: 00007f00705ed1f0 RCX: 00007f00704cd0f9
RDX: 0000000020000180 RSI: 0000000020000030 RDI: 0000000000080000
RBP: 00007f0070528ae9 R08: 0000000020000240 R09: 0000000020000240
R10: 00000000200001c0 R11: 0000000000000206 R12: 0000000000000000
R13: 00007ffc6ac8727f R14: 00007f006a5dc300 R15: 0000000000022000

Crashes (3):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2023/02/21 00:36 linux-4.19.y 3f8a27f9e27b 2414209c .config console log report info [disk image] [vmlinux] ci2-linux-4-19 INFO: task hung in rxrpc_release
2022/12/28 14:40 linux-4.19.y 3f8a27f9e27b 44712fbc .config console log report info [disk image] [vmlinux] ci2-linux-4-19 INFO: task hung in rxrpc_release
2022/10/17 23:48 linux-4.19.y 3f8a27f9e27b 94744d21 .config console log report info [disk image] [vmlinux] ci2-linux-4-19 INFO: task hung in rxrpc_release
* Struck through repros no longer work on HEAD.