syzbot


INFO: task hung in raw_release (2)

Status: upstream: reported syz repro on 2020/12/29 11:35
Reported-by: syzbot+600f542d6b472ad60c39@syzkaller.appspotmail.com
First crash: 1423d, last: 1318d
Fix bisection: failed (error log, bisect log)
  
Similar bugs (5)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-4.19 INFO: task hung in raw_release 1 1633d 1633d 0/1 auto-closed as invalid on 2020/09/30 10:18
upstream INFO: task hung in raw_release can 1388 1242d 1780d 0/28 closed as dup on 2021/06/26 09:46
upstream INFO: task hung in raw_release (2) can 47 116d 163d 0/28 closed as invalid on 2024/08/26 16:08
linux-5.15 INFO: task hung in raw_release 1 169d 169d 0/3 auto-obsoleted due to no activity on 2024/09/13 05:01
linux-6.1 INFO: task hung in raw_release 3 166d 181d 0/3 auto-obsoleted due to no activity on 2024/09/16 15:07

Sample crash report:
Bluetooth: hci5: command 0x0406 tx timeout
Bluetooth: hci3: command 0x0406 tx timeout
Bluetooth: hci4: command 0x0406 tx timeout
ieee802154 phy0 wpan0: encryption failed: -22
ieee802154 phy1 wpan1: encryption failed: -22
INFO: task syz-executor.2:11205 blocked for more than 140 seconds.
      Not tainted 4.19.186-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2  D28040 11205   9732 0x80000006
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
 __rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:589 [inline]
 rwsem_down_write_failed+0x3aa/0x760 kernel/locking/rwsem-xadd.c:618
 call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:117
 __down_write arch/x86/include/asm/rwsem.h:142 [inline]
 down_write+0x4f/0x90 kernel/locking/rwsem.c:72
 unregister_netdevice_notifier+0x7b/0x330 net/core/dev.c:1708
 raw_release+0x58/0x820 net/can/raw.c:358
 __sock_release+0xcd/0x2a0 net/socket.c:579
 sock_close+0x15/0x20 net/socket.c:1140
 __fput+0x2ce/0x890 fs/file_table.c:278
 task_work_run+0x148/0x1c0 kernel/task_work.c:113
 exit_task_work include/linux/task_work.h:22 [inline]
 do_exit+0xbf3/0x2be0 kernel/exit.c:870
 do_group_exit+0x125/0x310 kernel/exit.c:967
 get_signal+0x3f2/0x1f70 kernel/signal.c:2589
 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 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:0x466459
Code: Bad RIP value.
RSP: 002b:00007ffecdb219a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000029
RAX: 0000000000000004 RBX: 000000000056bf60 RCX: 0000000000466459
RDX: 0000000000000001 RSI: 0000000000000003 RDI: 000000000000001d
RBP: 00000000004bf9fb R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007ffecdb21b40 R14: 000000000056bf60 R15: 00000000001bef3b
INFO: task syz-executor.1:11217 blocked for more than 140 seconds.
      Not tainted 4.19.186-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.1  D28288 11217   9733 0x80000006
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
 __rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:589 [inline]
 rwsem_down_write_failed+0x3aa/0x760 kernel/locking/rwsem-xadd.c:618
 call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:117
 __down_write arch/x86/include/asm/rwsem.h:142 [inline]
 down_write+0x4f/0x90 kernel/locking/rwsem.c:72
 unregister_netdevice_notifier+0x7b/0x330 net/core/dev.c:1708
 raw_release+0x58/0x820 net/can/raw.c:358
 __sock_release+0xcd/0x2a0 net/socket.c:579
 sock_close+0x15/0x20 net/socket.c:1140
 __fput+0x2ce/0x890 fs/file_table.c:278
 task_work_run+0x148/0x1c0 kernel/task_work.c:113
 exit_task_work include/linux/task_work.h:22 [inline]
 do_exit+0xbf3/0x2be0 kernel/exit.c:870
 do_group_exit+0x125/0x310 kernel/exit.c:967
 get_signal+0x3f2/0x1f70 kernel/signal.c:2589
 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 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:0x466459
Code: Bad RIP value.
RSP: 002b:00007ffc7aee5b98 EFLAGS: 00000246 ORIG_RAX: 0000000000000029
RAX: 0000000000000004 RBX: 000000000056bf60 RCX: 0000000000466459
RDX: 0000000000000001 RSI: 0000000000000003 RDI: 000000000000001d
RBP: 00000000004bf9fb R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007ffc7aee5d30 R14: 000000000056bf60 R15: 00000000001bf128

Showing all locks held in the system:
1 lock held by khungtaskd/1569:
 #0: 000000008f0ebe13 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440
1 lock held by in:imklog/7796:
 #0: 00000000ece2a780 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767
2 locks held by syz-executor.2/11205:
 #0: 00000000f2fea4b1 (&sb->s_type->i_mutex_key#13){+.+.}, at: inode_lock include/linux/fs.h:748 [inline]
 #0: 00000000f2fea4b1 (&sb->s_type->i_mutex_key#13){+.+.}, at: __sock_release+0x86/0x2a0 net/socket.c:578
 #1: 00000000997e4e6b (pernet_ops_rwsem){++++}, at: unregister_netdevice_notifier+0x7b/0x330 net/core/dev.c:1708
2 locks held by syz-executor.1/11217:
 #0: 00000000703458f1 (&sb->s_type->i_mutex_key#13){+.+.}, at: inode_lock include/linux/fs.h:748 [inline]
 #0: 00000000703458f1 (&sb->s_type->i_mutex_key#13){+.+.}, at: __sock_release+0x86/0x2a0 net/socket.c:578
 #1: 00000000997e4e6b (pernet_ops_rwsem){++++}, at: unregister_netdevice_notifier+0x7b/0x330 net/core/dev.c:1708
2 locks held by syz-executor.2/11303:
2 locks held by syz-executor.4/11328:
1 lock held by syz-executor.1/11334:
2 locks held by syz-executor.0/11337:
1 lock held by syz-executor.5/11360:

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

NMI backtrace for cpu 0
CPU: 0 PID: 1569 Comm: khungtaskd Not tainted 4.19.186-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: 11334 Comm: syz-executor.1 Not tainted 4.19.186-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:native_apic_mem_write+0x8/0x10 arch/x86/include/asm/apic.h:109
Code: 3d dc ff 3c 0b 01 7f 02 5d c3 89 ef 5d e9 60 96 c8 06 48 c7 c7 00 77 65 8c e8 34 a7 6f 00 eb df 66 90 89 ff 89 b7 00 c0 5f ff <c3> 0f 1f 80 00 00 00 00 48 b8 00 00 00 00 00 fc ff df 53 89 fb 48
RSP: 0018:ffff8880ba107e98 EFLAGS: 00000046
RAX: dffffc0000000000 RBX: ffffffff89cf41a0 RCX: 0000000000000020
RDX: 1ffffffff139e836 RSI: 00000000000000d8 RDI: 0000000000000380
RBP: ffff8880ba11e280 R08: ffffffff8c663058 R09: 0000000000000000
R10: 0000000000000007 R11: ffffffff8c66305b R12: 00000000000000d8
R13: 0000000000000000 R14: ffff8880ba124c80 R15: ffff8880ba124cf0
FS:  00000000027d8400(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fc776a14000 CR3: 0000000097035000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 apic_write arch/x86/include/asm/apic.h:388 [inline]
 lapic_next_event+0x4d/0x80 arch/x86/kernel/apic/apic.c:460
 clockevents_program_event+0x234/0x350 kernel/time/clockevents.c:344
 tick_program_event+0xac/0x140 kernel/time/tick-oneshot.c:48
 hrtimer_interrupt+0x40a/0x9e0 kernel/time/hrtimer.c:1552
 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
 </IRQ>
RIP: 0010:__read_once_size include/linux/compiler.h:261 [inline]
RIP: 0010:inet_twsk_purge+0x154/0x7c0 net/ipv4/inet_timewait_sock.c:269
Code: e8 51 f2 8c fa 0f b6 1d 88 36 39 04 31 ff 89 de e8 71 f3 8c fa 84 db 0f 84 ea 04 00 00 e8 34 f2 8c fa 48 8b 44 24 18 80 38 00 <0f> 85 c8 04 00 00 48 8b 44 24 10 48 8b 28 49 89 ec 89 eb eb 21 e8
RSP: 0018:ffff8880815c7940 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: ffffed1046ae7125 RBX: 0000000000000001 RCX: ffffffff86d57086
RDX: 0000000000000000 RSI: ffffffff86d56b8c RDI: 0000000000000001
RBP: 0000000000067124 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000001 R11: 0000000000000000 R12: 00000000000ce249
R13: 0000000000000002 R14: 0000000000000002 R15: dffffc0000000000
 ops_exit_list+0xf9/0x150 net/core/net_namespace.c:156
 setup_net+0x3d1/0x720 net/core/net_namespace.c:332
 copy_net_ns+0x1f7/0x340 net/core/net_namespace.c:438
 create_new_namespaces+0x3f6/0x7b0 kernel/nsproxy.c:107
 copy_namespaces+0x325/0x3c0 kernel/nsproxy.c:165
 copy_process.part.0+0x3a59/0x8260 kernel/fork.c:1915
 copy_process kernel/fork.c:1709 [inline]
 _do_fork+0x22f/0xf30 kernel/fork.c:2218
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x466459
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 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 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007ffc7aee5b98 EFLAGS: 00000246 ORIG_RAX: 0000000000000038
RAX: ffffffffffffffda RBX: 000000000056bf60 RCX: 0000000000466459
RDX: 9999999999999999 RSI: 0000000000000000 RDI: 00000000e1004d7c
RBP: 00000000004bf9fb R08: ffffffffffffffff R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007ffc7aee5d30 R14: 000000000056bf60 R15: 00000000001bfd86

Crashes (4):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2021/04/13 12:23 linux-4.19.y 830a059cbba6 6a81331a .config console log report syz ci2-linux-4-19 INFO: task hung in raw_release
2021/03/22 18:16 linux-4.19.y 125222814e7b 8092f30d .config console log report info ci2-linux-4-19 INFO: task hung in raw_release
2021/03/09 10:04 linux-4.19.y 2cae3e25b706 09fbf400 .config console log report info ci2-linux-4-19 INFO: task hung in raw_release
2020/12/29 11:34 linux-4.19.y 13d2ce42de8c 8259d56c .config console log report info ci2-linux-4-19
* Struck through repros no longer work on HEAD.