INFO: rcu detected stall in __snd_pcm_lib_xfer

Status: closed as invalid on 2018/02/14 15:02
Subsystems: sound
[Documentation on labels]
Reported-by: syzbot+7667af42b3dfc7db58e8@syzkaller.appspotmail.com
First crash: 3161d, last: 3156d
▶ ▼ Similar bugs (1)
Kernel Title Rank 🛈 Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in __snd_pcm_lib_xfer (2) sound 1 syz, C 1064 3061d 3066d 5/29 fixed on 2018/05/08 18:30

Sample crash report:
INFO: rcu_sched self-detected stall on CPU
INFO: rcu_sched detected stalls on CPUs/tasks:
	0-....: (125000 ticks this GP) idle=92e/140000000000001/0 softirq=20924/20924 fqs=31236 
	(detected by 1, t=125002 jiffies, g=11537, c=11536, q=334)
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 8827 Comm: syz-executor7 Not tainted 4.15.0-rc5+ #244
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:inb arch/x86/include/asm/io.h:348 [inline]
RIP: 0010:io_serial_in+0x6b/0x90 drivers/tty/serial/8250/8250_port.c:434
RSP: 0018:ffff8801db206ff8 EFLAGS: 00000002
RAX: dffffc0000000000 RBX: 00000000000003fd RCX: 0000000000000000
RDX: 00000000000003fd RSI: 0000000000000005 RDI: ffffffff8885df58
RBP: ffff8801db207008 R08: 1ffff1003b640d57 R09: 000000000000000c
R10: ffff8801db206f78 R11: 0000000000000040 R12: ffffffff8885df20
R13: 0000000000000020 R14: fffffbfff110bc29 R15: fffffbfff110bbed
FS:  00007f4aff33c700(0000) GS:ffff8801db200000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000020fe7ed0 CR3: 00000001d8459002 CR4: 00000000001606f0
Call Trace:
 <IRQ>
 serial_in drivers/tty/serial/8250/8250.h:111 [inline]
 wait_for_xmitr+0x93/0x1e0 drivers/tty/serial/8250/8250_port.c:2033
 serial8250_console_putchar+0x1f/0x60 drivers/tty/serial/8250/8250_port.c:3170
 uart_console_write+0xac/0xe0 drivers/tty/serial/serial_core.c:1858
 serial8250_console_write+0x64f/0xa40 drivers/tty/serial/8250/8250_port.c:3236
 univ8250_console_write+0x5f/0x70 drivers/tty/serial/8250/8250_core.c:590
 call_console_drivers kernel/printk/printk.c:1574 [inline]
 console_unlock+0x788/0xd80 kernel/printk/printk.c:2233
 vprintk_emit+0x4ad/0x590 kernel/printk/printk.c:1757
 vprintk_default+0x28/0x30 kernel/printk/printk.c:1796
 vprintk_func+0x57/0xc0 kernel/printk/printk_safe.c:379
 printk+0xaa/0xca kernel/printk/printk.c:1829
 print_cpu_stall_info_begin kernel/rcu/tree_plugin.h:1657 [inline]
 print_cpu_stall kernel/rcu/tree.c:1594 [inline]
 check_cpu_stall.isra.61+0x845/0x15b0 kernel/rcu/tree.c:1676
 __rcu_pending kernel/rcu/tree.c:3440 [inline]
 rcu_pending kernel/rcu/tree.c:3502 [inline]
 rcu_check_callbacks+0x256/0xd00 kernel/rcu/tree.c:2842
 update_process_times+0x30/0x60 kernel/time/timer.c:1630
 tick_sched_handle+0x85/0x160 kernel/time/tick-sched.c:162
 tick_sched_timer+0x42/0x120 kernel/time/tick-sched.c:1179
 __run_hrtimer kernel/time/hrtimer.c:1211 [inline]
 __hrtimer_run_queues+0x358/0xe20 kernel/time/hrtimer.c:1275
 hrtimer_interrupt+0x1c2/0x5e0 kernel/time/hrtimer.c:1309
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1025 [inline]
 smp_apic_timer_interrupt+0x14a/0x700 arch/x86/kernel/apic/apic.c:1050
 apic_timer_interrupt+0xa9/0xb0 arch/x86/entry/entry_64.S:920
 </IRQ>
RIP: 0010:arch_local_irq_enable arch/x86/include/asm/paravirt.h:787 [inline]
RIP: 0010:snd_pcm_stream_unlock_irq+0xac/0xe0 sound/core/pcm_native.c:166
RSP: 0018:ffff8801c2f576d8 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff11
RAX: 0000000000010000 RBX: ffff8801d3bc82c0 RCX: ffffffff8419def5
RDX: 0000000000010000 RSI: ffffc900040f7000 RDI: ffffffff868c83b8
RBP: ffff8801c2f576e0 R08: 1ffff100385eaea9 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: ffffed00385eaf21
R13: dffffc0000000000 R14: 00000000fffffe00 R15: ffff8801d3b88940
 __snd_pcm_lib_xfer+0x1501/0x1b60 sound/core/pcm_lib.c:2251
 snd_pcm_oss_write3+0xe2/0x1b0 sound/core/oss/pcm_oss.c:1182
 io_playback_transfer+0x204/0x2b0 sound/core/oss/io.c:47
 snd_pcm_plug_write_transfer+0x2f1/0x420 sound/core/oss/pcm_plugin.c:611
 snd_pcm_oss_write2+0x260/0x420 sound/core/oss/pcm_oss.c:1311
 snd_pcm_oss_write1 sound/core/oss/pcm_oss.c:1372 [inline]
 snd_pcm_oss_write+0x5fe/0x830 sound/core/oss/pcm_oss.c:2646
 __vfs_write+0xef/0x970 fs/read_write.c:480
 vfs_write+0x189/0x510 fs/read_write.c:544
 SYSC_write fs/read_write.c:589 [inline]
 SyS_write+0xef/0x220 fs/read_write.c:581
 entry_SYSCALL_64_fastpath+0x23/0x9a
RIP: 0033:0x452ac9
RSP: 002b:00007f4aff33bc58 EFLAGS: 00000212 ORIG_RAX: 0000000000000001
RAX: ffffffffffffffda RBX: 00007f4aff33c700 RCX: 0000000000452ac9
RDX: 00000000fffffea3 RSI: 0000000020b6305d RDI: 0000000000000013
RBP: 0000000000a2f870 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000
R13: 0000000000a2f7ef R14: 00007f4aff33c9c0 R15: 0000000000000002
Code: 24 d1 00 00 00 49 8d 7c 24 38 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 d3 e3 80 3c 02 00 75 17 41 03 5c 24 38 89 da ec <5b> 0f b6 c0 41 5c 5d c3 e8 18 74 10 ff eb c2 e8 71 74 10 ff eb 
	0-....: (125000 ticks this GP) idle=92e/140000000000001/0 softirq=20924/20924 fqs=31425 
	 (t=125766 jiffies g=11537 c=11536 q=334)
NMI backtrace for cpu 0
CPU: 0 PID: 8827 Comm: syz-executor7 Not tainted 4.15.0-rc5+ #244
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 <IRQ>
 __dump_stack lib/dump_stack.c:17 [inline]
 dump_stack+0x194/0x257 lib/dump_stack.c:53
 nmi_cpu_backtrace+0x1d2/0x210 lib/nmi_backtrace.c:103
 nmi_trigger_cpumask_backtrace+0x122/0x180 lib/nmi_backtrace.c:62
 arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38
 trigger_single_cpu_backtrace include/linux/nmi.h:156 [inline]
 rcu_dump_cpu_stacks+0x186/0x1d9 kernel/rcu/tree.c:1459
 print_cpu_stall kernel/rcu/tree.c:1608 [inline]
 check_cpu_stall.isra.61+0xbb8/0x15b0 kernel/rcu/tree.c:1676
 __rcu_pending kernel/rcu/tree.c:3440 [inline]
 rcu_pending kernel/rcu/tree.c:3502 [inline]
 rcu_check_callbacks+0x256/0xd00 kernel/rcu/tree.c:2842
 update_process_times+0x30/0x60 kernel/time/timer.c:1630
 tick_sched_handle+0x85/0x160 kernel/time/tick-sched.c:162
 tick_sched_timer+0x42/0x120 kernel/time/tick-sched.c:1179
 __run_hrtimer kernel/time/hrtimer.c:1211 [inline]
 __hrtimer_run_queues+0x358/0xe20 kernel/time/hrtimer.c:1275
 hrtimer_interrupt+0x1c2/0x5e0 kernel/time/hrtimer.c:1309
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1025 [inline]
 smp_apic_timer_interrupt+0x14a/0x700 arch/x86/kernel/apic/apic.c:1050
 apic_timer_interrupt+0xa9/0xb0 arch/x86/entry/entry_64.S:920
 </IRQ>
RIP: 0010:arch_local_irq_enable arch/x86/include/asm/paravirt.h:787 [inline]
RIP: 0010:snd_pcm_stream_unlock_irq+0xac/0xe0 sound/core/pcm_native.c:166
RSP: 0018:ffff8801c2f576d8 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff11
RAX: 0000000000010000 RBX: ffff8801d3bc82c0 RCX: ffffffff8419def5
RDX: 0000000000010000 RSI: ffffc900040f7000 RDI: ffffffff868c83b8
RBP: ffff8801c2f576e0 R08: 1ffff100385eaea9 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: ffffed00385eaf21
R13: dffffc0000000000 R14: 00000000fffffe00 R15: ffff8801d3b88940
 __snd_pcm_lib_xfer+0x1501/0x1b60 sound/core/pcm_lib.c:2251
 snd_pcm_oss_write3+0xe2/0x1b0 sound/core/oss/pcm_oss.c:1182
 io_playback_transfer+0x204/0x2b0 sound/core/oss/io.c:47
 snd_pcm_plug_write_transfer+0x2f1/0x420 sound/core/oss/pcm_plugin.c:611
 snd_pcm_oss_write2+0x260/0x420 sound/core/oss/pcm_oss.c:1311
 snd_pcm_oss_write1 sound/core/oss/pcm_oss.c:1372 [inline]
 snd_pcm_oss_write+0x5fe/0x830 sound/core/oss/pcm_oss.c:2646
 __vfs_write+0xef/0x970 fs/read_write.c:480
 vfs_write+0x189/0x510 fs/read_write.c:544
 SYSC_write fs/read_write.c:589 [inline]
 SyS_write+0xef/0x220 fs/read_write.c:581
 entry_SYSCALL_64_fastpath+0x23/0x9a
RIP: 0033:0x452ac9
RSP: 002b:00007f4aff33bc58 EFLAGS: 00000212 ORIG_RAX: 0000000000000001
RAX: ffffffffffffffda RBX: 00007f4aff33c700 RCX: 0000000000452ac9
RDX: 00000000fffffea3 RSI: 0000000020b6305d RDI: 0000000000000013
RBP: 0000000000a2f870 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000
R13: 0000000000a2f7ef R14: 00007f4aff33c9c0 R15: 0000000000000002

Crashes (4):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2017/12/31 14:07 upstream 71ee203389f7 00193447 .config console log report ci-upstream-kasan-gce
2018/01/05 14:46 linux-next 990b6a07d18c 00193447 .config console log report ci-upstream-next-kasan-gce
2018/01/05 02:04 mmots ad036b63ee57 00193447 .config console log report ci-upstream-mmots-kasan-gce
2018/01/03 07:52 linux-next 73005e1a35fd 00193447 .config console log report ci-upstream-next-kasan-gce
* Struck through repros no longer work on HEAD.