INFO: task can't die in rfcomm_dlc_open

Status: auto-closed as invalid on 2021/10/12 22:04
Subsystems: bluetooth
[Documentation on labels]
Reported-by: syzbot+068daa0ce5cfeafef437@syzkaller.appspotmail.com
First crash: 1825d, last: 1825d

Sample crash report:
INFO: task syz-executor.0:23882 can't die for more than 143 seconds.
task:syz-executor.0  state:D stack:27424 pid:23882 ppid:  6597 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4711 [inline]
 __schedule+0x93a/0x26f0 kernel/sched/core.c:5966
 schedule+0xd3/0x270 kernel/sched/core.c:6045
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6104
 __mutex_lock_common kernel/locking/mutex.c:1036 [inline]
 __mutex_lock+0xa46/0x1300 kernel/locking/mutex.c:1096
 rfcomm_dlc_open+0x8f/0xa80 net/bluetooth/rfcomm/core.c:425
 rfcomm_sock_connect+0x32f/0x460 net/bluetooth/rfcomm/sock.c:413
 __sys_connect_file+0x155/0x1a0 net/socket.c:1890
 __sys_connect+0x161/0x190 net/socket.c:1907
 __do_sys_connect net/socket.c:1917 [inline]
 __se_sys_connect net/socket.c:1914 [inline]
 __x64_sys_connect+0x6f/0xb0 net/socket.c:1914
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665e9
RSP: 002b:00007f38ac0a8188 EFLAGS: 00000246 ORIG_RAX: 000000000000002a
RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665e9
RDX: 0000000000000080 RSI: 0000000020000000 RDI: 0000000000000005
RBP: 00000000004bfcc4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80
R13: 00007ffd8afbfbcf R14: 00007f38ac0a8300 R15: 0000000000022000
INFO: task syz-executor.0:23882 blocked for more than 143 seconds.
      Not tainted 5.14.0-rc5-next-20210813-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0  state:D stack:27424 pid:23882 ppid:  6597 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4711 [inline]
 __schedule+0x93a/0x26f0 kernel/sched/core.c:5966
 schedule+0xd3/0x270 kernel/sched/core.c:6045
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6104
 __mutex_lock_common kernel/locking/mutex.c:1036 [inline]
 __mutex_lock+0xa46/0x1300 kernel/locking/mutex.c:1096
 rfcomm_dlc_open+0x8f/0xa80 net/bluetooth/rfcomm/core.c:425
 rfcomm_sock_connect+0x32f/0x460 net/bluetooth/rfcomm/sock.c:413
 __sys_connect_file+0x155/0x1a0 net/socket.c:1890
 __sys_connect+0x161/0x190 net/socket.c:1907
 __do_sys_connect net/socket.c:1917 [inline]
 __se_sys_connect net/socket.c:1914 [inline]
 __x64_sys_connect+0x6f/0xb0 net/socket.c:1914
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665e9
RSP: 002b:00007f38ac0a8188 EFLAGS: 00000246 ORIG_RAX: 000000000000002a
RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665e9
RDX: 0000000000000080 RSI: 0000000020000000 RDI: 0000000000000005
RBP: 00000000004bfcc4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80
R13: 00007ffd8afbfbcf R14: 00007f38ac0a8300 R15: 0000000000022000
INFO: task syz-executor.0:23887 can't die for more than 143 seconds.
task:syz-executor.0  state:D stack:28808 pid:23887 ppid:  6597 flags:0x00004004
Call Trace:
 context_switch kernel/sched/core.c:4711 [inline]
 __schedule+0x93a/0x26f0 kernel/sched/core.c:5966
 schedule+0xd3/0x270 kernel/sched/core.c:6045
 __lock_sock+0x13d/0x260 net/core/sock.c:2645
 lock_sock_nested+0xf6/0x120 net/core/sock.c:3178
 lock_sock include/net/sock.h:1612 [inline]
 bt_sock_wait_state+0x249/0x590 net/bluetooth/af_bluetooth.c:557
 rfcomm_sock_connect+0x3a5/0x460 net/bluetooth/rfcomm/sock.c:416
 __sys_connect_file+0x155/0x1a0 net/socket.c:1890
 __sys_connect+0x161/0x190 net/socket.c:1907
 __do_sys_connect net/socket.c:1917 [inline]
 __se_sys_connect net/socket.c:1914 [inline]
 __x64_sys_connect+0x6f/0xb0 net/socket.c:1914
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665e9
RSP: 002b:00007f38ac087188 EFLAGS: 00000246 ORIG_RAX: 000000000000002a
RAX: ffffffffffffffda RBX: 000000000056c038 RCX: 00000000004665e9
RDX: 0000000000000080 RSI: 0000000020000000 RDI: 0000000000000005
RBP: 00000000004bfcc4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c038
R13: 00007ffd8afbfbcf R14: 00007f38ac087300 R15: 0000000000022000
INFO: task syz-executor.0:23887 blocked for more than 144 seconds.
      Not tainted 5.14.0-rc5-next-20210813-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0  state:D stack:28808 pid:23887 ppid:  6597 flags:0x00004004
Call Trace:
 context_switch kernel/sched/core.c:4711 [inline]
 __schedule+0x93a/0x26f0 kernel/sched/core.c:5966
 schedule+0xd3/0x270 kernel/sched/core.c:6045
 __lock_sock+0x13d/0x260 net/core/sock.c:2645
 lock_sock_nested+0xf6/0x120 net/core/sock.c:3178
 lock_sock include/net/sock.h:1612 [inline]
 bt_sock_wait_state+0x249/0x590 net/bluetooth/af_bluetooth.c:557
 rfcomm_sock_connect+0x3a5/0x460 net/bluetooth/rfcomm/sock.c:416
 __sys_connect_file+0x155/0x1a0 net/socket.c:1890
 __sys_connect+0x161/0x190 net/socket.c:1907
 __do_sys_connect net/socket.c:1917 [inline]
 __se_sys_connect net/socket.c:1914 [inline]
 __x64_sys_connect+0x6f/0xb0 net/socket.c:1914
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665e9
RSP: 002b:00007f38ac087188 EFLAGS: 00000246 ORIG_RAX: 000000000000002a
RAX: ffffffffffffffda RBX: 000000000056c038 RCX: 00000000004665e9
RDX: 0000000000000080 RSI: 0000000020000000 RDI: 0000000000000005
RBP: 00000000004bfcc4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c038
R13: 00007ffd8afbfbcf R14: 00007f38ac087300 R15: 0000000000022000
INFO: task syz-executor.0:23891 can't die for more than 144 seconds.
task:syz-executor.0  state:D stack:30272 pid:23891 ppid:  6597 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4711 [inline]
 __schedule+0x93a/0x26f0 kernel/sched/core.c:5966
 schedule+0xd3/0x270 kernel/sched/core.c:6045
 __lock_sock+0x13d/0x260 net/core/sock.c:2645
 lock_sock_nested+0xf6/0x120 net/core/sock.c:3178
 lock_sock include/net/sock.h:1612 [inline]
 rfcomm_sk_state_change+0xb4/0x390 net/bluetooth/rfcomm/sock.c:73
 __rfcomm_dlc_close+0x1b6/0x8a0 net/bluetooth/rfcomm/core.c:489
 rfcomm_dlc_close+0x1ea/0x240 net/bluetooth/rfcomm/core.c:520
 __rfcomm_sock_close+0xac/0x260 net/bluetooth/rfcomm/sock.c:220
 rfcomm_sock_shutdown+0xe9/0x210 net/bluetooth/rfcomm/sock.c:931
 __sys_shutdown_sock net/socket.c:2242 [inline]
 __sys_shutdown_sock net/socket.c:2236 [inline]
 __sys_shutdown+0xf1/0x1b0 net/socket.c:2254
 __do_sys_shutdown net/socket.c:2262 [inline]
 __se_sys_shutdown net/socket.c:2260 [inline]
 __x64_sys_shutdown+0x50/0x70 net/socket.c:2260
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665e9
RSP: 002b:00007f38ac066188 EFLAGS: 00000246 ORIG_RAX: 0000000000000030
RAX: ffffffffffffffda RBX: 000000000056c0f0 RCX: 00000000004665e9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000005
RBP: 00000000004bfcc4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c0f0
R13: 00007ffd8afbfbcf R14: 00007f38ac066300 R15: 0000000000022000
INFO: task syz-executor.0:23891 blocked for more than 144 seconds.
      Not tainted 5.14.0-rc5-next-20210813-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0  state:D stack:30272 pid:23891 ppid:  6597 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4711 [inline]
 __schedule+0x93a/0x26f0 kernel/sched/core.c:5966
 schedule+0xd3/0x270 kernel/sched/core.c:6045
 __lock_sock+0x13d/0x260 net/core/sock.c:2645
 lock_sock_nested+0xf6/0x120 net/core/sock.c:3178
 lock_sock include/net/sock.h:1612 [inline]
 rfcomm_sk_state_change+0xb4/0x390 net/bluetooth/rfcomm/sock.c:73
 __rfcomm_dlc_close+0x1b6/0x8a0 net/bluetooth/rfcomm/core.c:489
 rfcomm_dlc_close+0x1ea/0x240 net/bluetooth/rfcomm/core.c:520
 __rfcomm_sock_close+0xac/0x260 net/bluetooth/rfcomm/sock.c:220
 rfcomm_sock_shutdown+0xe9/0x210 net/bluetooth/rfcomm/sock.c:931
 __sys_shutdown_sock net/socket.c:2242 [inline]
 __sys_shutdown_sock net/socket.c:2236 [inline]
 __sys_shutdown+0xf1/0x1b0 net/socket.c:2254
 __do_sys_shutdown net/socket.c:2262 [inline]
 __se_sys_shutdown net/socket.c:2260 [inline]
 __x64_sys_shutdown+0x50/0x70 net/socket.c:2260
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665e9
RSP: 002b:00007f38ac066188 EFLAGS: 00000246 ORIG_RAX: 0000000000000030
RAX: ffffffffffffffda RBX: 000000000056c0f0 RCX: 00000000004665e9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000005
RBP: 00000000004bfcc4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c0f0
R13: 00007ffd8afbfbcf R14: 00007f38ac066300 R15: 0000000000022000
INFO: task syz-executor.0:23939 can't die for more than 144 seconds.
task:syz-executor.0  state:D stack:29168 pid:23939 ppid:  6597 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4711 [inline]
 __schedule+0x93a/0x26f0 kernel/sched/core.c:5966
 schedule+0xd3/0x270 kernel/sched/core.c:6045
 __lock_sock+0x13d/0x260 net/core/sock.c:2645
 lock_sock_nested+0xf6/0x120 net/core/sock.c:3178
 lock_sock include/net/sock.h:1612 [inline]
 rfcomm_sock_shutdown+0x54/0x210 net/bluetooth/rfcomm/sock.c:928
 __sys_shutdown_sock net/socket.c:2242 [inline]
 __sys_shutdown_sock net/socket.c:2236 [inline]
 __sys_shutdown+0xf1/0x1b0 net/socket.c:2254
 __do_sys_shutdown net/socket.c:2262 [inline]
 __se_sys_shutdown net/socket.c:2260 [inline]
 __x64_sys_shutdown+0x50/0x70 net/socket.c:2260
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665e9
RSP: 002b:00007f38ac024188 EFLAGS: 00000246 ORIG_RAX: 0000000000000030
RAX: ffffffffffffffda RBX: 000000000056c260 RCX: 00000000004665e9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000005
RBP: 00000000004bfcc4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c260
R13: 00007ffd8afbfbcf R14: 00007f38ac024300 R15: 0000000000022000
INFO: task syz-executor.0:23939 blocked for more than 144 seconds.
      Not tainted 5.14.0-rc5-next-20210813-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0  state:D stack:29168 pid:23939 ppid:  6597 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4711 [inline]
 __schedule+0x93a/0x26f0 kernel/sched/core.c:5966
 schedule+0xd3/0x270 kernel/sched/core.c:6045
 __lock_sock+0x13d/0x260 net/core/sock.c:2645
 lock_sock_nested+0xf6/0x120 net/core/sock.c:3178
 lock_sock include/net/sock.h:1612 [inline]
 rfcomm_sock_shutdown+0x54/0x210 net/bluetooth/rfcomm/sock.c:928
 __sys_shutdown_sock net/socket.c:2242 [inline]
 __sys_shutdown_sock net/socket.c:2236 [inline]
 __sys_shutdown+0xf1/0x1b0 net/socket.c:2254
 __do_sys_shutdown net/socket.c:2262 [inline]
 __se_sys_shutdown net/socket.c:2260 [inline]
 __x64_sys_shutdown+0x50/0x70 net/socket.c:2260
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665e9
RSP: 002b:00007f38ac024188 EFLAGS: 00000246 ORIG_RAX: 0000000000000030
RAX: ffffffffffffffda RBX: 000000000056c260 RCX: 00000000004665e9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000005
RBP: 00000000004bfcc4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c260
R13: 00007ffd8afbfbcf R14: 00007f38ac024300 R15: 0000000000022000
INFO: lockdep is turned off.
NMI backtrace for cpu 0
CPU: 0 PID: 27 Comm: khungtaskd Not tainted 5.14.0-rc5-next-20210813-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+0xcd/0x134 lib/dump_stack.c:106
 nmi_cpu_backtrace.cold+0x47/0x144 lib/nmi_backtrace.c:105
 nmi_trigger_cpumask_backtrace+0x1ae/0x220 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:254 [inline]
 watchdog+0xcb7/0xed0 kernel/hung_task.c:339
 kthread+0x3e5/0x4d0 kernel/kthread.c:319
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 6875 Comm: kworker/u4:3 Not tainted 5.14.0-rc5-next-20210813-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: bat_events batadv_nc_worker
RIP: 0010:rcu_read_unlock_sched_notrace include/linux/rcupdate.h:809 [inline]
RIP: 0010:trace_lock_release include/trace/events/lock.h:58 [inline]
RIP: 0010:lock_release+0x52d/0x720 kernel/locking/lockdep.c:5636
Code: c0 0f 85 a5 fe ff ff e9 ec 91 92 07 65 ff 05 da d0 a6 7e 48 8b 05 d3 ac fe 0b e8 9e e8 05 00 85 c0 74 17 65 ff 0d c3 d0 a6 7e <0f> 85 7c fb ff ff e8 e7 26 a5 ff e9 72 fb ff ff e8 2e a6 d4 07 85
RSP: 0018:ffffc9000314fbd0 EFLAGS: 00000286
RAX: 0000000000000001 RBX: 0000000000000001 RCX: ffffffff815b1ab1
RDX: fffffbfff1adbe4b RSI: 0000000000000008 RDI: ffffffff8d6df250
RBP: 1ffff92000629f7c R08: 0000000000000000 R09: ffffffff8d6df257
R10: fffffbfff1adbe4a R11: 0000000000000000 R12: ffffffff8b97eb80
R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000208
FS:  0000000000000000(0000) GS:ffff8880b9d00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fed8d193028 CR3: 000000001a680000 CR4: 00000000001506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 rcu_lock_release include/linux/rcupdate.h:272 [inline]
 rcu_read_unlock include/linux/rcupdate.h:720 [inline]
 batadv_nc_purge_orig_hash net/batman-adv/network-coding.c:408 [inline]
 batadv_nc_worker+0x771/0xe50 net/batman-adv/network-coding.c:715
 process_one_work+0x98d/0x1630 kernel/workqueue.c:2275
 worker_thread+0x658/0x11f0 kernel/workqueue.c:2421
 kthread+0x3e5/0x4d0 kernel/kthread.c:319
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295
----------------
Code disassembly (best guess), 1 bytes skipped:
   0:	0f 85 a5 fe ff ff    	jne    0xfffffeab
   6:	e9 ec 91 92 07       	jmpq   0x79291f7
   b:	65 ff 05 da d0 a6 7e 	incl   %gs:0x7ea6d0da(%rip)        # 0x7ea6d0ec
  12:	48 8b 05 d3 ac fe 0b 	mov    0xbfeacd3(%rip),%rax        # 0xbfeacec
  19:	e8 9e e8 05 00       	callq  0x5e8bc
  1e:	85 c0                	test   %eax,%eax
  20:	74 17                	je     0x39
  22:	65 ff 0d c3 d0 a6 7e 	decl   %gs:0x7ea6d0c3(%rip)        # 0x7ea6d0ec
  29:	0f 85 7c fb ff ff    	jne    0xfffffbab <-- trapping instruction
  2f:	e8 e7 26 a5 ff       	callq  0xffa5271b
  34:	e9 72 fb ff ff       	jmpq   0xfffffbab
  39:	e8 2e a6 d4 07       	callq  0x7d4a66c
  3e:	85                   	.byte 0x85

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2021/08/13 22:04 linux-next 4b358aabb93a 3fd2ea69 .config console log report info ci-upstream-linux-next-kasan-gce-root INFO: task can't die in rfcomm_dlc_open
* Struck through repros no longer work on HEAD.