syzbot


INFO: rcu detected stall in snd_pcm_oss_read

Status: auto-closed as invalid on 2020/09/01 05:31
Reported-by: syzbot+79ad473a0fe15096f6d7@syzkaller.appspotmail.com
First crash: 794d, last: 794d
similar bugs (1):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in snd_pcm_oss_read C 2 1291d 1291d 12/22 fixed on 2019/03/06 07:43

Sample crash report:
loop4: p3 size 2 extends beyond EOD, truncated
loop4: p4 size 32768 extends beyond EOD, truncated
loop4: p5 size 1073741824 extends beyond EOD, truncated
loop4: p6 size 32768 extends beyond EOD, truncated
INFO: rcu_sched detected stalls on CPUs/tasks:
	(detected by 1, t=10502 jiffies, g=19822, c=19821, q=5)
All QSes seen, last rcu_sched kthread activity 10498 (4295100765-4295090267), jiffies_till_next_fqs=1, root ->qsmask 0x0
syz-executor.0  R  running task    28016 18070  13552 0x00000000
Call Trace:
 <IRQ>
 sched_show_task kernel/sched/core.c:5168 [inline]
 sched_show_task.cold+0x2e3/0x347 kernel/sched/core.c:5143
 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+0xd3f/0xdaa kernel/rcu/tree.c:2792
 update_process_times+0x29/0x60 kernel/time/timer.c:1590
 tick_sched_handle+0x7d/0x150 kernel/time/tick-sched.c:165
 tick_sched_timer+0x3e/0x120 kernel/time/tick-sched.c:1223
 __run_hrtimer kernel/time/hrtimer.c:1223 [inline]
 __hrtimer_run_queues+0x25c/0xbb0 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+0x8f/0xa0 arch/x86/entry/entry_64.S:792
 </IRQ>
RIP: 0010:preempt_count_sub+0x63/0x130 kernel/sched/core.c:3143
RSP: 0018:ffff8880a868fb10 EFLAGS: 00000297 ORIG_RAX: ffffffffffffff10
RAX: 0000000080000001 RBX: 00000000ffffffff RCX: 0000000000000000
RDX: 0000000000000004 RSI: 0000000000000000 RDI: 0000000000000001
RBP: ffff8880a868fc80 R08: dffffc0000000000 R09: ffffffff8a075c90
R10: ffff88808c150ac8 R11: ffff88808c150240 R12: fffffbfff14b1758
R13: ffffffff8a58bac0 R14: ffff88808c150240 R15: ffff8880532a56c0
 __mutex_lock_common kernel/locking/mutex.c:764 [inline]
 __mutex_lock+0x36a/0x1470 kernel/locking/mutex.c:893
 snd_pcm_oss_read1 sound/core/oss/pcm_oss.c:1504 [inline]
 snd_pcm_oss_read+0x298/0x5e0 sound/core/oss/pcm_oss.c:2753
 __vfs_read+0xe4/0x610 fs/read_write.c:411
 vfs_read+0x131/0x330 fs/read_write.c:447
 SYSC_read fs/read_write.c:574 [inline]
 SyS_read+0xf2/0x210 fs/read_write.c:567
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45c829
RSP: 002b:00007f6d93c63c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
RAX: ffffffffffffffda RBX: 00000000004fa880 RCX: 000000000045c829
RDX: 00000000000000cc RSI: 00000000200005c0 RDI: 0000000000000008
RBP: 000000000078bfa0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000000000000875 R14: 00000000004c9d3f R15: 00007f6d93c646d4
rcu_sched kthread starved for 10498 jiffies! g19822 c19821 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=1
rcu_sched       R  running task    29984     9      2 0x80000000
Call Trace:
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_timeout+0x43c/0xe40 kernel/time/timer.c:1746
 rcu_gp_kthread+0xb8e/0x1ce0 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_preempt detected stalls on CPUs/tasks:
	Tasks blocked on level-0 rcu_node (CPUs 0-1): P3638
	(detected by 1, t=10538 jiffies, g=53697, c=53696, q=71)
systemd-udevd   R  running task    25488  3638      1 0x00000100
Call Trace:
 preempt_schedule_common+0x4a/0xc0 kernel/sched/core.c:3508
 ___preempt_schedule+0x16/0x18
 __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:161 [inline]
 _raw_spin_unlock_irqrestore+0xb7/0xe0 kernel/locking/spinlock.c:192
 spin_unlock_irqrestore include/linux/spinlock.h:372 [inline]
 __wake_up_common_lock+0xcd/0x140 kernel/sched/wait.c:126
 sock_def_readable+0xf3/0x3f0 net/core/sock.c:2628
 __netlink_sendskb+0x78/0x90 net/netlink/af_netlink.c:1234
 netlink_sendskb net/netlink/af_netlink.c:1240 [inline]
 netlink_unicast+0x50a/0x620 net/netlink/af_netlink.c:1328
 netlink_sendmsg+0x733/0xbe0 net/netlink/af_netlink.c:1878
 sock_sendmsg_nosec net/socket.c:646 [inline]
 sock_sendmsg+0xc5/0x100 net/socket.c:656
 ___sys_sendmsg+0x70a/0x840 net/socket.c:2062
 __sys_sendmsg+0xa3/0x120 net/socket.c:2096
 SYSC_sendmsg net/socket.c:2107 [inline]
 SyS_sendmsg+0x27/0x40 net/socket.c:2103
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x7fa002722e67
RSP: 002b:00007ffe97b896d8 EFLAGS: 00000246 ORIG_RAX: 000000000000002e
RAX: ffffffffffffffda RBX: 000055b66adaed50 RCX: 00007fa002722e67
RDX: 0000000000000000 RSI: 00007ffe97b896f0 RDI: 0000000000000004
RBP: 00007ffe97b896f0 R08: 000055b66ad9c154 R09: 0000000000000000
R10: 0000000000000018 R11: 0000000000000246 R12: 000055b66ada90f0
R13: 000000000000009c R14: 000055b66adaa370 R15: 0000000000000000
systemd-udevd   R  running task    25488  3638      1 0x00000100
Call Trace:
 preempt_schedule_common+0x4a/0xc0 kernel/sched/core.c:3508
 ___preempt_schedule+0x16/0x18
 __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:161 [inline]
 _raw_spin_unlock_irqrestore+0xb7/0xe0 kernel/locking/spinlock.c:192
 spin_unlock_irqrestore include/linux/spinlock.h:372 [inline]
 __wake_up_common_lock+0xcd/0x140 kernel/sched/wait.c:126
 sock_def_readable+0xf3/0x3f0 net/core/sock.c:2628
 __netlink_sendskb+0x78/0x90 net/netlink/af_netlink.c:1234
 netlink_sendskb net/netlink/af_netlink.c:1240 [inline]
 netlink_unicast+0x50a/0x620 net/netlink/af_netlink.c:1328
 netlink_sendmsg+0x733/0xbe0 net/netlink/af_netlink.c:1878
 sock_sendmsg_nosec net/socket.c:646 [inline]
 sock_sendmsg+0xc5/0x100 net/socket.c:656
 ___sys_sendmsg+0x70a/0x840 net/socket.c:2062
 __sys_sendmsg+0xa3/0x120 net/socket.c:2096
 SYSC_sendmsg net/socket.c:2107 [inline]
 SyS_sendmsg+0x27/0x40 net/socket.c:2103
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x7fa002722e67
RSP: 002b:00007ffe97b896d8 EFLAGS: 00000246 ORIG_RAX: 000000000000002e
RAX: ffffffffffffffda RBX: 000055b66adaed50 RCX: 00007fa002722e67
RDX: 0000000000000000 RSI: 00007ffe97b896f0 RDI: 0000000000000004
RBP: 00007ffe97b896f0 R08: 000055b66ad9c154 R09: 0000000000000000
R10: 0000000000000018 R11: 0000000000000246 R12: 000055b66ada90f0
R13: 000000000000009c R14: 000055b66adaa370 R15: 0000000000000000
rcu_preempt kthread starved for 10536 jiffies! g53697 c53696 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=1
rcu_preempt     R  running task    29920     8      2 0x80000000
Call Trace:
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_timeout+0x43c/0xe40 kernel/time/timer.c:1746
 rcu_gp_kthread+0xb8e/0x1ce0 kernel/rcu/tree.c:2255
 kthread+0x30d/0x420 kernel/kthread.c:232
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
sched: RT throttling activated

Crashes (1):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci2-linux-4-14 2020/05/04 05:31 linux-4.14.y 773e2b1cd56a 58ae5e18 .config log report