syzbot


INFO: rcu detected stall in socket

Status: upstream: reported syz repro on 2020/03/08 14:27
Reported-by: syzbot+6a2722264904ff7bf07d@syzkaller.appspotmail.com
First crash: 1503d, last: 522d
Fix bisection: failed (error log, bisect log)
  
Last patch testing requests (2)
Created Duration User Patch Repo Result
2022/11/11 13:30 11m retest repro linux-4.14.y report log
2022/11/11 12:30 9m retest repro linux-4.14.y report log

Sample crash report:
Bluetooth: hci2 command 0x041b tx timeout
INFO: rcu_preempt detected stalls on CPUs/tasks:
	(detected by 0, t=10502 jiffies, g=23576, c=23575, q=234)
All QSes seen, last rcu_preempt kthread activity 10502 (4295102875-4295092373), jiffies_till_next_fqs=1, root ->qsmask 0x0
syz-executor.0  R  running task    28912 31143  30774 0x0000000a
Call Trace:
 <IRQ>
 sched_show_task.cold+0x333/0x39a kernel/sched/core.c:5165
 print_other_cpu_stall kernel/rcu/tree.c:1501 [inline]
 check_cpu_stall kernel/rcu/tree.c:1616 [inline]
 __rcu_pending kernel/rcu/tree.c:3390 [inline]
 rcu_pending kernel/rcu/tree.c:3452 [inline]
 rcu_check_callbacks.cold+0xd29/0xd99 kernel/rcu/tree.c:2792
 update_process_times+0x29/0x60 kernel/time/timer.c:1591
 tick_sched_handle+0x7d/0x150 kernel/time/tick-sched.c:165
 tick_sched_timer+0x92/0x200 kernel/time/tick-sched.c:1223
 __run_hrtimer kernel/time/hrtimer.c:1223 [inline]
 __hrtimer_run_queues+0x30b/0xc80 kernel/time/hrtimer.c:1287
 hrtimer_interrupt+0x1e6/0x5e0 kernel/time/hrtimer.c:1321
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1075 [inline]
 smp_apic_timer_interrupt+0x117/0x5e0 arch/x86/kernel/apic/apic.c:1100
 apic_timer_interrupt+0x93/0xa0 arch/x86/entry/entry_64.S:793
 </IRQ>
RIP: 0010:__read_once_size include/linux/compiler.h:183 [inline]
RIP: 0010:atomic_read arch/x86/include/asm/atomic.h:27 [inline]
RIP: 0010:queued_write_lock_slowpath+0xcb/0x1d0 kernel/locking/qrwlock.c:139
RSP: 0018:ffff888093857d38 EFLAGS: 00000206 ORIG_RAX: ffffffffffffff10
RAX: 0000000000000101 RBX: ffffffff89dd2868 RCX: 0000000000006908
RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffffff89dd2868
RBP: ffffffff89dd286c R08: ffffffff8b9be300 R09: 00000000000405a6
R10: ffff8880a39c29b0 R11: ffff8880a39c2100 R12: 0000000000000003
R13: fffffbfff13ba50d R14: 00000000000000ff R15: ffffffff886382c0
 queued_write_lock include/asm-generic/qrwlock.h:134 [inline]
 do_raw_write_lock+0xc2/0x1d0 kernel/locking/spinlock_debug.c:203
 bt_sock_link+0x1f/0x120 net/bluetooth/af_bluetooth.c:143
 hci_sock_create+0x15c/0x1d0 net/bluetooth/hci_sock.c:2017
 bt_sock_create+0x13b/0x280 net/bluetooth/af_bluetooth.c:130
 __sock_create+0x303/0x620 net/socket.c:1275
 sock_create net/socket.c:1315 [inline]
 SYSC_socket net/socket.c:1345 [inline]
 SyS_socket+0xd1/0x1b0 net/socket.c:1325
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x45e219
RSP: 002b:00007fe44b5bcc08 EFLAGS: 00000246 ORIG_RAX: 0000000000000029
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045e219
RDX: 0000000000000001 RSI: 0000000000000003 RDI: 000000000000001f
RBP: 000000000119bfc0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000119bf8c
R13: 00007fffc916c19f R14: 00007fe44b5bd9c0 R15: 000000000119bf8c
rcu_preempt kthread starved for 10502 jiffies! g23576 c23575 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=1
rcu_preempt     R  running task    30008     8      2 0x80000000
Call Trace:
 context_switch kernel/sched/core.c:2808 [inline]
 __schedule+0x88b/0x1de0 kernel/sched/core.c:3384
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_timeout+0x4af/0xe90 kernel/time/timer.c:1747
 rcu_gp_kthread+0xc0a/0x1e60 kernel/rcu/tree.c:2255
 kthread+0x30d/0x420 kernel/kthread.c:232
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
INFO: rcu_sched detected stalls on CPUs/tasks:
	1-...: (10535 ticks this GP) idle=46a/140000000000000/0 softirq=107501/107501 fqs=0 
	(detected by 0, t=10502 jiffies, g=10643, c=10642, q=6)
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 29024 Comm: syz-executor.5 Not tainted 4.14.214-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
task: ffff8880b5040480 task.stack: ffff888096510000
RIP: 0010:pv_wait_head_or_lock kernel/locking/qspinlock_paravirt.h:411 [inline]
RIP: 0010:__pv_queued_spin_lock_slowpath+0x265/0x910 kernel/locking/qspinlock.c:465
RSP: 0018:ffff888096517a58 EFLAGS: 00000206
RAX: 0000000000000003 RBX: ffff8880ba52ae80 RCX: fffffbfff13ba50d
RDX: 0000000000000e08 RSI: 0000000000000004 RDI: 0000000000000286
RBP: ffffffff89dd286c R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000001 R11: 0000000000000000 R12: 0000000000000000
R13: dffffc0000000000 R14: ffffffff89dd286d R15: ffffed10174a55d8
FS:  0000000001f50940(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007ffd92169ff8 CR3: 00000000a9805000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:669 [inline]
 queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:52 [inline]
 queued_spin_lock include/asm-generic/qspinlock.h:90 [inline]
 queued_read_lock_slowpath+0x150/0x190 kernel/locking/qrwlock.c:90
 hci_send_to_channel+0x3c/0x200 net/bluetooth/hci_sock.c:261
 hci_send_monitor_ctrl_event+0x3dd/0x500 net/bluetooth/hci_sock.c:391
 mgmt_send_event+0x2b3/0x330 net/bluetooth/mgmt_util.c:86
 mgmt_index_event net/bluetooth/mgmt.c:253 [inline]
 mgmt_index_removed+0x200/0x240 net/bluetooth/mgmt.c:6594
 hci_unregister_dev+0x6f0/0x8c0 net/bluetooth/hci_core.c:3197
 vhci_release+0x70/0xe0 drivers/bluetooth/hci_vhci.c:354
 __fput+0x25f/0x7a0 fs/file_table.c:210
 task_work_run+0x11f/0x190 kernel/task_work.c:113
 exit_task_work include/linux/task_work.h:22 [inline]
 do_exit+0xa44/0x2850 kernel/exit.c:868
 do_group_exit+0x100/0x2e0 kernel/exit.c:965
 SYSC_exit_group kernel/exit.c:976 [inline]
 SyS_exit_group+0x19/0x20 kernel/exit.c:974
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x45e219
RSP: 002b:00007ffd9216ccd8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 000000000045e219
RDX: 0000000000417ab1 RSI: 00000000016b9df0 RDI: 0000000000000043
RBP: 00000000004c3d3e R08: 000000000000000b R09: 0000000000000000
R10: 0000000001f50940 R11: 0000000000000246 R12: 000000000000008a
R13: 0000000000000009 R14: 0000000000000032 R15: 000000000017a981
Code: 84 c0 0f 85 2e 04 00 00 48 89 e9 48 89 ee c6 45 01 01 ba 00 80 00 00 48 c1 e9 03 83 e6 07 41 b8 01 00 00 00 4c 01 e9 eb 0b f3 90 <83> ea 01 0f 84 42 04 00 00 0f b6 01 40 38 f0 7f 08 84 c0 0f 85 
rcu_sched kthread starved for 10502 jiffies! g10643 c10642 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_sched       R  running task    30008     9      2 0x80000000
Call Trace:
 context_switch kernel/sched/core.c:2808 [inline]
 __schedule+0x88b/0x1de0 kernel/sched/core.c:3384
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_timeout+0x4af/0xe90 kernel/time/timer.c:1747
 rcu_gp_kthread+0xc0a/0x1e60 kernel/rcu/tree.c:2255
 kthread+0x30d/0x420 kernel/kthread.c:232
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404

Crashes (25):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2021/01/12 03:50 linux-4.14.y ec822b3e8bf4 2c1f2513 .config console log report syz ci2-linux-4-14
2020/03/08 14:26 linux-4.14.y 78d697fc93f9 2e9971bb .config console log report syz ci2-linux-4-14
2021/02/26 05:44 linux-4.14.y 3242aa3a635c 76f7fc95 .config console log report info ci2-linux-4-14 INFO: rcu detected stall in socket
2022/11/14 11:24 linux-4.14.y e911713e40ca 943f4cb8 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-4-14 BUG: soft lockup in socket
2022/07/26 17:21 linux-4.14.y 9c3bf9cf362f 279b89c2 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/07/20 11:45 linux-4.14.y 424a46ea058e 775344bc .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/07/12 15:27 linux-4.14.y 424a46ea058e d91dd8ea .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/06/11 07:57 linux-4.14.y b8f3be299d51 0d5abf15 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/04/01 01:41 linux-4.14.y af1af6ebca0e 68fc921a .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/03/25 03:27 linux-4.14.y 004bfaafc45c 89bc8608 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/02/27 14:58 linux-4.14.y fa33f9094f36 45a13a73 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/01/26 03:56 linux-4.14.y 4ba8e26127c3 2cbffd88 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/01/20 16:02 linux-4.14.y 4ba8e26127c3 b838eb76 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/01/19 16:24 linux-4.14.y 4ba8e26127c3 0620189b .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/01/18 10:33 linux-4.14.y 4ba8e26127c3 731a2d23 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/01/14 14:58 linux-4.14.y 4ba8e26127c3 53e00b45 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2022/01/13 19:15 linux-4.14.y 4ba8e26127c3 b8d780ab .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2021/12/07 03:57 linux-4.14.y 66722c42ec91 0230ba3e .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2021/12/06 20:35 linux-4.14.y 66722c42ec91 579a8754 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2021/04/27 17:17 linux-4.14.y cf256fbcbe34 805b5003 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2021/04/25 06:29 linux-4.14.y cf256fbcbe34 17f0b706 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2021/04/21 02:22 linux-4.14.y cf256fbcbe34 c0ced557 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2021/04/20 01:37 linux-4.14.y cf256fbcbe34 4285c989 .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2021/04/06 09:28 linux-4.14.y bd634aa64163 6a81331a .config console log report info ci2-linux-4-14 BUG: soft lockup in socket
2020/12/30 13:24 linux-4.14.y 1752938529c6 0fa352f2 .config console log report info ci2-linux-4-14
* Struck through repros no longer work on HEAD.