Skip to contentSkip to conversation

XRUN traces

DISCUSSION Lee Revell 3 participants 12 messages Jul 13, 2004 – Jul 17, 2004 Char and misc drivers

lore
Share & tools
lore

Lee Revell

author
#0 lore raw

OK, I am starting a new thread here, as the other was getting unwieldy.

This one is 100% reproducible, happens immediately when I start JACK. I have set CONFIG_FRAME_POINTER=y and CONFIG_4KSTACKS=n as you requested.

Jul 13 18:30:21 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 18:30:21 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 18:30:21 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 18:30:21 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 18:30:21 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:30:21 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:30:21 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:30:21 mindpipe kernel:  [do_anonymous_page+124/384] do_anonymous_page+0x7c/0x180
Jul 13 18:30:21 mindpipe kernel:  [do_no_page+78/784] do_no_page+0x4e/0x310
Jul 13 18:30:21 mindpipe kernel:  [handle_mm_fault+193/368] handle_mm_fault+0xc1/0x170
Jul 13 18:30:21 mindpipe kernel:  [get_user_pages+258/880] get_user_pages+0x102/0x370
Jul 13 18:30:21 mindpipe kernel:  [make_pages_present+104/144] make_pages_present+0x68/0x90
Jul 13 18:30:21 mindpipe kernel:  [do_mmap_pgoff+1000/1552] do_mmap_pgoff+0x3e8/0x610
Jul 13 18:30:21 mindpipe kernel:  [sys_mmap2+118/176] sys_mmap2+0x76/0xb0
Jul 13 18:30:21 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 18:30:21 mindpipe kernel:
Jul 13 18:30:56 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 18:30:56 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 18:30:56 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 18:30:56 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 18:30:56 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:30:56 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:30:56 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:30:56 mindpipe kernel:  [handle_mm_fault+71/368] handle_mm_fault+0x47/0x170
Jul 13 18:30:56 mindpipe kernel:  [get_user_pages+258/880] get_user_pages+0x102/0x370
Jul 13 18:30:56 mindpipe kernel:  [make_pages_present+104/144] make_pages_present+0x68/0x90
Jul 13 18:30:56 mindpipe kernel:  [do_mmap_pgoff+1000/1552] do_mmap_pgoff+0x3e8/0x610
Jul 13 18:30:56 mindpipe kernel:  [sys_mmap2+118/176] sys_mmap2+0x76/0xb0
Jul 13 18:30:56 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 18:30:56 mindpipe kernel:
Jul 13 18:30:58 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 18:30:58 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 18:30:58 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 18:30:58 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 18:30:58 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:30:58 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:30:58 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:30:58 mindpipe kernel:  [do_anonymous_page+124/384] do_anonymous_page+0x7c/0x180
Jul 13 18:30:58 mindpipe kernel:  [do_no_page+78/784] do_no_page+0x4e/0x310
Jul 13 18:30:58 mindpipe kernel:  [handle_mm_fault+193/368] handle_mm_fault+0xc1/0x170
Jul 13 18:30:58 mindpipe kernel:  [get_user_pages+258/880] get_user_pages+0x102/0x370
Jul 13 18:30:58 mindpipe kernel:  [make_pages_present+104/144] make_pages_present+0x68/0x90
Jul 13 18:30:58 mindpipe kernel:  [do_mmap_pgoff+1000/1552] do_mmap_pgoff+0x3e8/0x610
Jul 13 18:30:58 mindpipe kernel:  [sys_mmap2+118/176] sys_mmap2+0x76/0xb0
Jul 13 18:30:58 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 18:30:58 mindpipe kernel:

Lee

#1 lore raw
Quoted 4 lines · Lee Revell

This one is 100% reproducible, happens immediately when I start JACK. I have set CONFIG_FRAME_POINTER=y and CONFIG_4KSTACKS=n as you requested.

Quoted 11 lines
Jul 13 18:30:21 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 18:30:21 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 18:30:21 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 18:30:21 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 18:30:21 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:30:21 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:30:21 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:30:21 mindpipe kernel:  [do_anonymous_page+124/384] do_anonymous_page+0x7c/0x180
Jul 13 18:30:21 mindpipe kernel:  [do_no_page+78/784] do_no_page+0x4e/0x310
Jul 13 18:30:21 mindpipe kernel:  [handle_mm_fault+193/368] handle_mm_fault+0xc1/0x170
Jul 13 18:30:21 mindpipe kernel:  [get_user_pages+258/880] get_user_pages+0x102/0x370

OK, I'll fix get_user_pages().

author
#2 lore raw

Ext3 vs. ReiserFS results (was Re: XRUN traces)

Quoted 17 lines · Andrew Morton
Quoted 4 lines · Lee Revell

This one is 100% reproducible, happens immediately when I start JACK. I have set CONFIG_FRAME_POINTER=y and CONFIG_4KSTACKS=n as you requested.

Quoted 11 lines
Jul 13 18:30:21 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 18:30:21 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 18:30:21 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 18:30:21 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 18:30:21 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:30:21 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:30:21 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:30:21 mindpipe kernel:  [do_anonymous_page+124/384] do_anonymous_page+0x7c/0x180
Jul 13 18:30:21 mindpipe kernel:  [do_no_page+78/784] do_no_page+0x4e/0x310
Jul 13 18:30:21 mindpipe kernel:  [handle_mm_fault+193/368] handle_mm_fault+0xc1/0x170
Jul 13 18:30:21 mindpipe kernel:  [get_user_pages+258/880] get_user_pages+0x102/0x370
Quoted 2 lines

OK, I'll fix get_user_pages().
-

I have copied my system onto an ext3 filesystem and ran the same test. It seems to be much better. These are all the XRUNs from running my JACK test while untarring a kernel tree in /tmp while doing `find / -ls'. There are not too many. Previously I had to send excerpts, this is the entire log. With reiserfs, I was not having to artificially stress the filesystem at all to produce WAY more XRUN traces than this.

Jul 13 19:47:53 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:47:53 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:47:53 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:47:53 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:47:53 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:47:53 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:47:53 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:47:53 mindpipe kernel:
Jul 13 19:48:04 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:48:04 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:48:04 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:48:04 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:48:04 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:48:04 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:48:04 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:48:04 mindpipe kernel:
Jul 13 19:48:05 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:48:05 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:48:05 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:48:05 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:48:05 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:48:05 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:48:05 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:48:05 mindpipe kernel:
Jul 13 19:48:06 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:48:06 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:48:06 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:48:06 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:48:06 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:48:06 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:48:06 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:48:06 mindpipe kernel:
Jul 13 19:48:11 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:48:11 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:48:11 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:48:11 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:48:11 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:48:11 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:48:11 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:48:11 mindpipe kernel:  [generic_file_aio_write_nolock+1044/2944] generic_file_aio_write_nolock+0x414/0xb80
Jul 13 19:48:11 mindpipe kernel:  [generic_file_aio_write+98/144] generic_file_aio_write+0x62/0x90
Jul 13 19:48:11 mindpipe kernel:  [ext3_file_write+40/192] ext3_file_write+0x28/0xc0
Jul 13 19:48:11 mindpipe kernel:  [do_sync_write+123/176] do_sync_write+0x7b/0xb0
Jul 13 19:48:11 mindpipe kernel:  [vfs_write+188/272] vfs_write+0xbc/0x110
Jul 13 19:48:11 mindpipe kernel:  [sys_write+46/80] sys_write+0x2e/0x50
Jul 13 19:48:11 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 19:48:11 mindpipe kernel:
Jul 13 19:48:32 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:48:32 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:48:32 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:48:32 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:48:32 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:48:32 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:48:32 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:48:32 mindpipe kernel:
Jul 13 19:48:34 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:48:34 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:48:34 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:48:34 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:48:34 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:48:34 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:48:34 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:48:34 mindpipe kernel:
Jul 13 19:49:04 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:49:04 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:49:04 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:49:04 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:49:04 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:49:04 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:49:04 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:49:04 mindpipe kernel:  [schedule_timeout+87/160] schedule_timeout+0x57/0xa0
Jul 13 19:49:04 mindpipe kernel:  [do_poll+161/192] do_poll+0xa1/0xc0
Jul 13 19:49:04 mindpipe kernel:  [sys_poll+305/544] sys_poll+0x131/0x220
Jul 13 19:49:04 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 19:49:04 mindpipe kernel:
Jul 13 19:49:05 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:49:05 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:49:05 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:49:05 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:49:05 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:49:05 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:49:05 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:49:05 mindpipe kernel:  [schedule+731/1424] schedule+0x2db/0x590
Jul 13 19:49:05 mindpipe kernel:  [work_resched+5/22] work_resched+0x5/0x16
Jul 13 19:49:05 mindpipe kernel:
Jul 13 19:49:26 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:49:26 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:49:26 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:49:26 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:49:26 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:49:26 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:49:26 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:49:26 mindpipe kernel:
Jul 13 19:49:54 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:49:54 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:49:54 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:49:54 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:49:54 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:49:54 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:49:54 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:49:54 mindpipe kernel:
Jul 13 19:50:05 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:50:05 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:50:05 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:50:05 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:50:05 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:50:05 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:50:05 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:50:05 mindpipe kernel:
Jul 13 19:50:46 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:50:46 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:50:46 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:50:46 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:50:46 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:50:46 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:50:46 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:50:46 mindpipe kernel:  [rcu_process_callbacks+150/256] rcu_process_callbacks+0x96/0x100
Jul 13 19:50:46 mindpipe kernel:  [tasklet_action+68/112] tasklet_action+0x44/0x70
Jul 13 19:50:46 mindpipe kernel:  [__do_softirq+131/144] __do_softirq+0x83/0x90
Jul 13 19:50:46 mindpipe kernel:  [do_softirq+53/64] do_softirq+0x35/0x40
Jul 13 19:50:46 mindpipe kernel:  [do_IRQ+277/368] do_IRQ+0x115/0x170
Jul 13 19:50:46 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:50:46 mindpipe kernel:
Jul 13 19:51:41 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:51:41 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:51:41 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:51:41 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:51:41 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:51:41 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:51:41 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:51:41 mindpipe kernel:  [do_softirq+53/64] do_softirq+0x35/0x40
Jul 13 19:51:41 mindpipe kernel:  [do_IRQ+277/368] do_IRQ+0x115/0x170
Jul 13 19:51:41 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:51:41 mindpipe kernel:
Jul 13 19:51:42 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c XRUN: pcmC0D0p
Jul 13 19:51:42 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:51:42 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:51:42 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:51:42 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:51:42 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:51:42 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:51:42 mindpipe kernel:  [scrup+243/320] scrup+0xf3/0x140
Jul 13 19:51:42 mindpipe kernel:  [lf+96/112] lf+0x60/0x70
Jul 13 19:51:42 mindpipe kernel:  [do_con_trol+2955/3408] do_con_trol+0xb8b/0xd50
Jul 13 19:51:42 mindpipe kernel:  [do_con_write+1071/1792] do_con_write+0x42f/0x700
Jul 13 19:51:42 mindpipe kernel:  [con_put_char+49/64] con_put_char+0x31/0x40
Jul 13 19:51:42 mindpipe kernel:  [opost+178/464] opost+0xb2/0x1d0
Jul 13 19:51:42 mindpipe kernel:  [write_chan+424/528] write_chan+0x1a8/0x210
Jul 13 19:51:42 mindpipe kernel:  [tty_write+247/448] tty_write+0xf7/0x1c0
Jul 13 19:51:42 mindpipe kernel:  [vfs_write+188/272] vfs_write+0xbc/0x110
Jul 13 19:51:42 mindpipe kernel:  [sys_write+46/80] sys_write+0x2e/0x50
Jul 13 19:51:42 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 19:51:42 mindpipe kernel:
Jul 13 19:51:45 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:51:45 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:51:45 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:51:45 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:51:45 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:51:45 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:51:45 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:51:45 mindpipe kernel:  [d_callback+29/64] d_callback+0x1d/0x40
Jul 13 19:51:45 mindpipe kernel:  [rcu_do_batch+62/80] rcu_do_batch+0x3e/0x50
Jul 13 19:51:45 mindpipe kernel:  [rcu_process_callbacks+150/256] rcu_process_callbacks+0x96/0x100
Jul 13 19:51:45 mindpipe kernel:  [tasklet_action+68/112] tasklet_action+0x44/0x70
Jul 13 19:51:45 mindpipe kernel:  [__do_softirq+131/144] __do_softirq+0x83/0x90
Jul 13 19:51:45 mindpipe kernel:  [do_softirq+53/64] do_softirq+0x35/0x40
Jul 13 19:51:45 mindpipe kernel:  [do_IRQ+277/368] do_IRQ+0x115/0x170
Jul 13 19:51:45 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:51:45 mindpipe kernel:  [poll_freewait+46/80] poll_freewait+0x2e/0x50
Jul 13 19:51:45 mindpipe kernel:  [sys_poll+472/544] sys_poll+0x1d8/0x220
Jul 13 19:51:45 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 19:51:45 mindpipe kernel:

Lee

#3 lore raw

Re: Ext3 vs. ReiserFS results (was Re: XRUN traces)

Quoted 10 lines · Lee Revell
Jul 13 19:51:45 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
 Jul 13 19:51:45 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
 Jul 13 19:51:45 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
 Jul 13 19:51:45 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
 Jul 13 19:51:45 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
 Jul 13 19:51:45 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
 Jul 13 19:51:45 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
 Jul 13 19:51:45 mindpipe kernel:  [d_callback+29/64] d_callback+0x1d/0x40
 Jul 13 19:51:45 mindpipe kernel:  [rcu_do_batch+62/80] rcu_do_batch+0x3e/0x50
Quoted 8 lines
 Jul 13 19:51:45 mindpipe kernel:  [rcu_process_callbacks+150/256] rcu_process_callbacks+0x96/0x100
 Jul 13 19:51:45 mindpipe kernel:  [tasklet_action+68/112] tasklet_action+0x44/0x70
 Jul 13 19:51:45 mindpipe kernel:  [__do_softirq+131/144] __do_softirq+0x83/0x90
 Jul 13 19:51:45 mindpipe kernel:  [do_softirq+53/64] do_softirq+0x35/0x40
 Jul 13 19:51:45 mindpipe kernel:  [do_IRQ+277/368] do_IRQ+0x115/0x170
 Jul 13 19:51:45 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
 Jul 13 19:51:45 mindpipe kernel:  [poll_freewait+46/80] poll_freewait+0x2e/0x50
 Jul 13 19:51:45 mindpipe kernel:  [sys_poll+472/544] sys_poll+0x1d8/0x220

This one's going to be a problem. It's due to RCU freeing a large number of dentries in one hit.

author
#4 lore raw

Re: Ext3 vs. ReiserFS results (was Re: XRUN traces)

Quoted 20 lines · Andrew Morton
Quoted 10 lines · Lee Revell
Jul 13 19:51:45 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
 Jul 13 19:51:45 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
 Jul 13 19:51:45 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
 Jul 13 19:51:45 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
 Jul 13 19:51:45 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
 Jul 13 19:51:45 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
 Jul 13 19:51:45 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
 Jul 13 19:51:45 mindpipe kernel:  [d_callback+29/64] d_callback+0x1d/0x40
 Jul 13 19:51:45 mindpipe kernel:  [rcu_do_batch+62/80] rcu_do_batch+0x3e/0x50
Quoted 8 lines
 Jul 13 19:51:45 mindpipe kernel:  [rcu_process_callbacks+150/256] rcu_process_callbacks+0x96/0x100
 Jul 13 19:51:45 mindpipe kernel:  [tasklet_action+68/112] tasklet_action+0x44/0x70
 Jul 13 19:51:45 mindpipe kernel:  [__do_softirq+131/144] __do_softirq+0x83/0x90
 Jul 13 19:51:45 mindpipe kernel:  [do_softirq+53/64] do_softirq+0x35/0x40
 Jul 13 19:51:45 mindpipe kernel:  [do_IRQ+277/368] do_IRQ+0x115/0x170
 Jul 13 19:51:45 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
 Jul 13 19:51:45 mindpipe kernel:  [poll_freewait+46/80] poll_freewait+0x2e/0x50
 Jul 13 19:51:45 mindpipe kernel:  [sys_poll+472/544] sys_poll+0x1d8/0x220
Quoted 3 lines

This one's going to be a problem. It's due to RCU freeing a large number of dentries in one hit.

It seems like the only time you would get this kind of situation in everyday use is `updatedb'. I will see if that triggers the same error.

Lee

#5 lore raw

Re: Ext3 vs. ReiserFS results (was Re: XRUN traces)

In reply to Andrew Morton

At Tue, 13 Jul 2004 17:58:33 -0700,

Quoted 21 lines · Andrew Morton
Quoted 10 lines · Lee Revell
Jul 13 19:51:45 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
 Jul 13 19:51:45 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
 Jul 13 19:51:45 mindpipe kernel:  [__crc_totalram_pages+562733/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
 Jul 13 19:51:45 mindpipe kernel:  [__crc_totalram_pages+631725/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
 Jul 13 19:51:45 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
 Jul 13 19:51:45 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
 Jul 13 19:51:45 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
 Jul 13 19:51:45 mindpipe kernel:  [d_callback+29/64] d_callback+0x1d/0x40
 Jul 13 19:51:45 mindpipe kernel:  [rcu_do_batch+62/80] rcu_do_batch+0x3e/0x50
Quoted 8 lines
 Jul 13 19:51:45 mindpipe kernel:  [rcu_process_callbacks+150/256] rcu_process_callbacks+0x96/0x100
 Jul 13 19:51:45 mindpipe kernel:  [tasklet_action+68/112] tasklet_action+0x44/0x70
 Jul 13 19:51:45 mindpipe kernel:  [__do_softirq+131/144] __do_softirq+0x83/0x90
 Jul 13 19:51:45 mindpipe kernel:  [do_softirq+53/64] do_softirq+0x35/0x40
 Jul 13 19:51:45 mindpipe kernel:  [do_IRQ+277/368] do_IRQ+0x115/0x170
 Jul 13 19:51:45 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
 Jul 13 19:51:45 mindpipe kernel:  [poll_freewait+46/80] poll_freewait+0x2e/0x50
 Jul 13 19:51:45 mindpipe kernel:  [sys_poll+472/544] sys_poll+0x1d8/0x220
Quoted 2 lines

This one's going to be a problem. It's due to RCU freeing a large number of dentries in one hit.

The recent patches to daemonize softirq may help?

There was a discussion on LKML (on March?) about the latency improvement of RCU. Rearming the tasklet fixed the latency problem indeed.

Takashi

author
#6 lore raw
In reply to Andrew Morton
Quoted 17 lines · Andrew Morton
Quoted 4 lines · Lee Revell

This one is 100% reproducible, happens immediately when I start JACK. I have set CONFIG_FRAME_POINTER=y and CONFIG_4KSTACKS=n as you requested.

Quoted 11 lines
Jul 13 18:30:21 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 18:30:21 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 18:30:21 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 18:30:21 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 18:30:21 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:30:21 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:30:21 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:30:21 mindpipe kernel:  [do_anonymous_page+124/384] do_anonymous_page+0x7c/0x180
Jul 13 18:30:21 mindpipe kernel:  [do_no_page+78/784] do_no_page+0x4e/0x310
Jul 13 18:30:21 mindpipe kernel:  [handle_mm_fault+193/368] handle_mm_fault+0xc1/0x170
Jul 13 18:30:21 mindpipe kernel:  [get_user_pages+258/880] get_user_pages+0x102/0x370
Quoted 1 line

OK, I'll fix get_user_pages().

This is the only one I am still seeing that does not involve get_user_pages(). I am seeing quite a lot with get_user_pages, but very few of these:

Jul 14 02:40:56 mindpipe kernel: 
Jul 14 02:40:56 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 14 02:40:56 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 14 02:40:56 mindpipe kernel:  [__crc_totalram_pages+168797/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 14 02:40:56 mindpipe kernel:  [__crc_totalram_pages+344957/3558647] snd_emu10k1_interrupt+0x337/0x3c0 [snd_emu10k1]
Jul 14 02:40:56 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 14 02:40:56 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 14 02:40:56 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 14 02:40:56 mindpipe kernel:  [pty_write+301/320] pty_write+0x12d/0x140
Jul 14 02:40:56 mindpipe kernel:  [opost_block+231/416] opost_block+0xe7/0x1a0
Jul 14 02:40:56 mindpipe kernel:  [write_chan+382/528] write_chan+0x17e/0x210
Jul 14 02:40:56 mindpipe kernel:  [tty_write+247/448] tty_write+0xf7/0x1c0
Jul 14 02:40:56 mindpipe kernel:  [vfs_write+188/272] vfs_write+0xbc/0x110
Jul 14 02:40:56 mindpipe kernel:  [sys_write+46/80] sys_write+0x2e/0x50
Jul 14 02:40:56 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 14 02:40:56 mindpipe kernel:

Lee

#7 lore raw
Quoted 4 lines · Lee Revell

This is the only one I am still seeing that does not involve
get_user_pages(). I am seeing quite a lot with get_user_pages, but very few of these:

get_user_pages() shold be fixed in rc1-mm1. But that kernel is busted, so wait until I have a fix.

Quoted 7 lines
 Jul 14 02:40:56 mindpipe kernel: 
 Jul 14 02:40:56 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
 Jul 14 02:40:56 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
 Jul 14 02:40:56 mindpipe kernel:  [__crc_totalram_pages+168797/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
 Jul 14 02:40:56 mindpipe kernel:  [__crc_totalram_pages+344957/3558647] snd_emu10k1_interrupt+0x337/0x3c0 [snd_emu10k1]
 Jul 14 02:40:56 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
 Jul 14 02:40:56 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Quoted 8 lines
 Jul 14 02:40:56 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
 Jul 14 02:40:56 mindpipe kernel:  [pty_write+301/320] pty_write+0x12d/0x140
 Jul 14 02:40:56 mindpipe kernel:  [opost_block+231/416] opost_block+0xe7/0x1a0
 Jul 14 02:40:56 mindpipe kernel:  [write_chan+382/528] write_chan+0x17e/0x210
 Jul 14 02:40:56 mindpipe kernel:  [tty_write+247/448] tty_write+0xf7/0x1c0
 Jul 14 02:40:56 mindpipe kernel:  [vfs_write+188/272] vfs_write+0xbc/0x110
 Jul 14 02:40:56 mindpipe kernel:  [sys_write+46/80] sys_write+0x2e/0x50
 Jul 14 02:40:56 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb

Try this:

drivers/char/pty.c renamed from drivers/char/pty.c~pty_write-latency-fix +5 -2
@@ -126,7 +126,8 @@ static int pty_write(struct tty_struct *
 			n = to->ldisc.receive_room(to);
 			if (n > count)
 				n = count;
-			if (!n) break;
+			if (!n)
+				break;
 
 			n  = min(n, PTY_BUF_SIZE);
 			n -= copy_from_user(temp_buffer, buf, n);

@@ -140,11 +141,13 @@ static int pty_write(struct tty_struct *
 			room = to->ldisc.receive_room(to);
 			if (n > room)
 				n = room;
-			if (!n) break;
+			if (!n)
+				break;
 			buf   += n; 
 			c     += n;
 			count -= n;
 			to->ldisc.receive_buf(to, temp_buffer, NULL, n);
+			cond_resched();
 		}
 		up(&tty->flip.pty_sem);
 	} else {

_

author
#8 lore raw

Here are a few new ones:

Jul 14 10:39:20 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 14 10:39:20 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 14 10:39:20 mindpipe kernel:  [__crc_totalram_pages+639837/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 14 10:39:20 mindpipe kernel:  [__crc_totalram_pages+705405/3558647] snd_emu10k1_interrupt+0x337/0x3c0 [snd_emu10k1]
Jul 14 10:39:20 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 14 10:39:20 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 14 10:39:20 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 14 10:39:20 mindpipe kernel:  [__do_softirq+131/144] __do_softirq+0x83/0x90
Jul 14 10:39:20 mindpipe kernel:  [do_softirq+53/64] do_softirq+0x35/0x40
Jul 14 10:39:20 mindpipe kernel:  [do_IRQ+277/368] do_IRQ+0x115/0x170
Jul 14 10:39:20 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 14 10:39:20 mindpipe kernel:  [sock_alloc_send_pskb+184/464] sock_alloc_send_pskb+0xb8/0x1d0
Jul 14 10:39:20 mindpipe kernel:  [sock_alloc_send_skb+26/32] sock_alloc_send_skb+0x1a/0x20
Jul 14 10:39:20 mindpipe kernel:  [unix_stream_sendmsg+387/976] unix_stream_sendmsg+0x183/0x3d0
Jul 14 10:39:20 mindpipe kernel:  [sock_sendmsg+129/176] sock_sendmsg+0x81/0xb0
Jul 14 10:39:20 mindpipe kernel:  [sock_readv_writev+101/144] sock_readv_writev+0x65/0x90
Jul 14 10:39:20 mindpipe kernel:  [sock_writev+59/80] sock_writev+0x3b/0x50
Jul 14 10:39:20 mindpipe kernel:  [do_readv_writev+504/592] do_readv_writev+0x1f8/0x250
Jul 14 10:39:20 mindpipe kernel:  [vfs_writev+68/80] vfs_writev+0x44/0x50
Jul 14 10:39:20 mindpipe kernel:  [sys_writev+46/80] sys_writev+0x2e/0x50
Jul 14 10:39:20 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 14 10:41:59 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 14 10:41:59 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 14 10:41:59 mindpipe kernel:  [__crc_totalram_pages+639837/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 14 10:41:59 mindpipe kernel:  [__crc_totalram_pages+705405/3558647] snd_emu10k1_interrupt+0x337/0x3c0 [snd_emu10k1]
Jul 14 10:41:59 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 14 10:41:59 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 14 10:41:59 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 14 10:41:59 mindpipe kernel:  [send_group_sig_info+32/64] send_group_sig_info+0x20/0x40
Jul 14 10:41:59 mindpipe kernel:  [it_real_fn+17/96] it_real_fn+0x11/0x60
Jul 14 10:41:59 mindpipe kernel:  [run_timer_softirq+204/416] run_timer_softirq+0xcc/0x1a0
Jul 14 10:41:59 mindpipe kernel:  [__do_softirq+131/144] __do_softirq+0x83/0x90
Jul 14 10:41:59 mindpipe kernel:  [do_softirq+53/64] do_softirq+0x35/0x40
Jul 14 10:41:59 mindpipe kernel:  [do_IRQ+277/368] do_IRQ+0x115/0x170
Jul 14 10:41:59 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 14 10:41:59 mindpipe kernel: 
Jul 14 10:41:59 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 14 10:41:59 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 14 10:41:59 mindpipe kernel:  [__crc_totalram_pages+639837/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 14 10:41:59 mindpipe kernel:  [__crc_totalram_pages+705405/3558647] snd_emu10k1_interrupt+0x337/0x3c0 [snd_emu10k1]
Jul 14 10:41:59 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 14 10:41:59 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 14 10:41:59 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 14 10:41:59 mindpipe kernel:  [poll_freewait+68/80] poll_freewait+0x44/0x50
Jul 14 10:41:59 mindpipe kernel:  [sys_poll+472/544] sys_poll+0x1d8/0x220
Jul 14 10:41:59 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 14 10:42:00 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D2c
Jul 14 10:42:00 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 14 10:42:00 mindpipe kernel:  [__crc_totalram_pages+639837/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 14 10:42:00 mindpipe kernel:  [__crc_totalram_pages+704791/3558647] snd_emu10k1_interrupt+0xd1/0x3c0 [snd_emu10k1]
Jul 14 10:42:00 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 14 10:42:00 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 14 10:42:00 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 14 10:42:00 mindpipe kernel:  [do_anonymous_page+124/384] do_anonymous_page+0x7c/0x180
Jul 14 10:42:00 mindpipe kernel:  [do_no_page+78/784] do_no_page+0x4e/0x310
Jul 14 10:42:00 mindpipe kernel:  [handle_mm_fault+193/368] handle_mm_fault+0xc1/0x170
Jul 14 10:42:00 mindpipe kernel:  [do_page_fault+805/1356] do_page_fault+0x325/0x54c
Jul 14 10:42:00 mindpipe kernel:  [error_code+45/64] error_code+0x2d/0x40
Jul 14 10:57:36 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 14 10:57:36 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 14 10:57:36 mindpipe kernel:  [__crc_totalram_pages+639837/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 14 10:57:36 mindpipe kernel:  [__crc_totalram_pages+705405/3558647] snd_emu10k1_interrupt+0x337/0x3c0 [snd_emu10k1]
Jul 14 10:57:36 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 14 10:57:36 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 14 10:57:36 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 14 10:57:36 mindpipe kernel:  [__crc_totalram_pages+617342/3558647] snd_pcm_common_ioctl1+0x168/0x300 [snd_pcm]
Jul 14 10:57:36 mindpipe kernel:  [__crc_totalram_pages+618749/3558647] snd_pcm_capture_ioctl1+0x47/0x330 [snd_pcm]
Jul 14 10:57:36 mindpipe kernel:  [sys_ioctl+256/608] sys_ioctl+0x100/0x260
Jul 14 10:57:36 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 14 11:11:08 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D2c
Jul 14 11:11:08 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 14 11:11:08 mindpipe kernel:  [__crc_totalram_pages+639837/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 14 11:11:08 mindpipe kernel:  [__crc_totalram_pages+704791/3558647] snd_emu10k1_interrupt+0xd1/0x3c0 [snd_emu10k1]
Jul 14 11:11:08 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 14 11:11:08 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 14 11:11:08 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 14 11:11:08 mindpipe kernel:  [poll_freewait+46/80] poll_freewait+0x2e/0x50
Jul 14 11:11:08 mindpipe kernel:  [sys_poll+472/544] sys_poll+0x1d8/0x220
Jul 14 11:11:08 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 14 11:11:16 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D2c
Jul 14 11:11:16 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 14 11:11:16 mindpipe kernel:  [__crc_totalram_pages+639837/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 14 11:11:16 mindpipe kernel:  [__crc_totalram_pages+704791/3558647] snd_emu10k1_interrupt+0xd1/0x3c0 [snd_emu10k1]
Jul 14 11:11:16 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 14 11:11:16 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 14 11:11:16 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 14 11:11:16 mindpipe kernel:  [i8042_timer_func+14/32] i8042_timer_func+0xe/0x20
Jul 14 11:11:16 mindpipe kernel:  [run_timer_softirq+204/416] run_timer_softirq+0xcc/0x1a0
Jul 14 11:11:16 mindpipe kernel:  [__do_softirq+131/144] __do_softirq+0x83/0x90
Jul 14 11:11:16 mindpipe kernel:  [do_softirq+53/64] do_softirq+0x35/0x40
Jul 14 11:11:16 mindpipe kernel:  [do_IRQ+277/368] do_IRQ+0x115/0x170
Jul 14 11:11:16 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20

Lee

author
#9 lore raw
In reply to Andrew Morton
Quoted 10 lines · Andrew Morton
Quoted 4 lines · Lee Revell

This is the only one I am still seeing that does not involve
get_user_pages(). I am seeing quite a lot with get_user_pages, but very few of these:

get_user_pages() shold be fixed in rc1-mm1. But that kernel is busted, so wait until I have a fix.

With 2.6.8-rc1-mm1, when I have started a jackd process in the playback direction, and launch another in the capture direction, I can reliably trigger a ~150ms XRUN in the first process. The duration of the XRUN is exactly the same each time, it is probably a funciton of the amount of memory being mlockall()'ed.

Otherwise this kernel seems pretty good.

Jul 16 20:57:29 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D2c
Jul 16 20:57:29 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 16 20:57:29 mindpipe kernel:  [__crc_totalram_pages+115469/3518512] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 16 20:57:29 mindpipe kernel:  [__crc_totalram_pages+180567/3518512] snd_emu10k1_interrupt+0xd1/0x3c0 [snd_emu10k1]
Jul 16 20:57:29 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 16 20:57:29 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 16 20:57:29 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 16 20:57:29 mindpipe kernel:  [__alloc_pages+167/816] __alloc_pages+0xa7/0x330
Jul 16 20:57:29 mindpipe kernel:  [do_anonymous_page+96/384] do_anonymous_page+0x60/0x180
Jul 16 20:57:29 mindpipe kernel:  [do_no_page+78/784] do_no_page+0x4e/0x310
Jul 16 20:57:29 mindpipe kernel:  [handle_mm_fault+193/368] handle_mm_fault+0xc1/0x170
Jul 16 20:57:29 mindpipe kernel:  [get_user_pages+337/960] get_user_pages+0x151/0x3c0
Jul 16 20:57:29 mindpipe kernel:  [make_pages_present+104/144] make_pages_present+0x68/0x90
Jul 16 20:57:29 mindpipe kernel:  [mlock_fixup+141/176] mlock_fixup+0x8d/0xb0
Jul 16 20:57:29 mindpipe kernel:  [do_mlockall+112/144] do_mlockall+0x70/0x90
Jul 16 20:57:29 mindpipe kernel:  [sys_mlockall+150/160] sys_mlockall+0x96/0xa0
Jul 16 20:57:29 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb

Lee

author
#10 lore raw
Quoted 7 lines · Lee Revell
Quoted 6 lines · Andrew Morton
Quoted 4 lines · Lee Revell

This is the only one I am still seeing that does not involve
get_user_pages(). I am seeing quite a lot with get_user_pages, but very few of these:

Quoted 7 lines
Quoted 4 lines

get_user_pages() shold be fixed in rc1-mm1. But that kernel is busted, so wait until I have a fix.

Otherwise this kernel seems pretty good.

I am also getting this one. This is with 2.6.8-rc1-mm1 + La Monte H.P. Yarroll's patch to daemonize softirqs.

Jul 16 23:10:23 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D2c
Jul 16 23:10:23 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 16 23:10:23 mindpipe kernel:  [__crc_totalram_pages+115469/3518512] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 16 23:10:23 mindpipe kernel:  [__crc_totalram_pages+291159/3518512] snd_emu10k1_interrupt+0xd1/0x3c0 [snd_emu10k1]
Jul 16 23:10:23 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 16 23:10:23 mindpipe kernel:  [do_IRQ+161/320] do_IRQ+0xa1/0x140
Jul 16 23:10:23 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 16 23:10:23 mindpipe kernel:  [copy_to_user+45/64] copy_to_user+0x2d/0x40
Jul 16 23:10:23 mindpipe kernel:  [memcpy_toiovec+44/96] memcpy_toiovec+0x2c/0x60
Jul 16 23:10:23 mindpipe kernel:  [unix_stream_recvmsg+370/1024] unix_stream_recvmsg+0x172/0x400
Jul 16 23:10:23 mindpipe kernel:  [sock_aio_read+220/256] sock_aio_read+0xdc/0x100
Jul 16 23:10:23 mindpipe kernel:  [do_sync_read+106/160] do_sync_read+0x6a/0xa0
Jul 16 23:10:23 mindpipe kernel:  [vfs_read+227/272] vfs_read+0xe3/0x110
Jul 16 23:10:23 mindpipe kernel:  [sys_read+46/80] sys_read+0x2e/0x50
Jul 16 23:10:23 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 16 23:10:23 mindpipe kernel: ALSA /home/rlrevell/cvs/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 16 23:10:23 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 16 23:10:23 mindpipe kernel:  [__crc_totalram_pages+115469/3518512] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 16 23:10:23 mindpipe kernel:  [__crc_totalram_pages+291773/3518512] snd_emu10k1_interrupt+0x337/0x3c0 [snd_emu10k1]
Jul 16 23:10:23 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 16 23:10:23 mindpipe kernel:  [do_IRQ+161/320] do_IRQ+0xa1/0x140
Jul 16 23:10:23 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 16 23:10:23 mindpipe kernel:  [copy_to_user+45/64] copy_to_user+0x2d/0x40
Jul 16 23:10:23 mindpipe kernel:  [memcpy_toiovec+44/96] memcpy_toiovec+0x2c/0x60
Jul 16 23:10:23 mindpipe kernel:  [unix_stream_recvmsg+370/1024] unix_stream_recvmsg+0x172/0x400
Jul 16 23:10:23 mindpipe kernel:  [sock_aio_read+220/256] sock_aio_read+0xdc/0x100
Jul 16 23:10:23 mindpipe kernel:  [do_sync_read+106/160] do_sync_read+0x6a/0xa0
Jul 16 23:10:23 mindpipe kernel:  [vfs_read+227/272] vfs_read+0xe3/0x110
Jul 16 23:10:23 mindpipe kernel:  [sys_read+46/80] sys_read+0x2e/0x50
Jul 16 23:10:23 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb

Lee

author
#11 lore raw
In reply to Lee Revell
Quoted 5 lines · Lee Revell

OK, I am starting a new thread here, as the other was getting unwieldy.

This one is 100% reproducible, happens immediately when I start JACK. I have set CONFIG_FRAME_POINTER=y and CONFIG_4KSTACKS=n as you requested.

After removing (un)lock_kernel from do_tty_write, I can still cause an XRUN by switching consoles, but it it much less common that before. I have not seen any display problems at all.

Jul 13 19:09:04 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:09:04 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:09:04 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:09:04 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:09:04 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:09:04 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:09:04 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:09:04 mindpipe kernel:
Jul 13 19:09:05 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:09:05 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:09:05 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:09:05 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:09:05 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:09:05 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:09:05 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:09:05 mindpipe kernel:
Jul 13 19:09:05 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:09:05 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:09:05 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:09:05 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:09:05 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:09:05 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:09:05 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:09:05 mindpipe kernel:  [con_put_char+49/64] con_put_char+0x31/0x40
Jul 13 19:09:05 mindpipe kernel:  [opost+178/464] opost+0xb2/0x1d0
Jul 13 19:09:05 mindpipe kernel:  [write_chan+424/528] write_chan+0x1a8/0x210
Jul 13 19:09:05 mindpipe kernel:  [tty_write+247/448] tty_write+0xf7/0x1c0
Jul 13 19:09:05 mindpipe kernel:  [vfs_write+188/272] vfs_write+0xbc/0x110
Jul 13 19:09:05 mindpipe kernel:  [sys_write+46/80] sys_write+0x2e/0x50
Jul 13 19:09:05 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 19:09:05 mindpipe kernel:
Jul 13 19:09:05 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:09:05 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:09:05 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:09:05 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:09:05 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:09:05 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:09:05 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:09:05 mindpipe kernel:  [scrup+243/320] scrup+0xf3/0x140
Jul 13 19:09:05 mindpipe kernel:  [lf+96/112] lf+0x60/0x70
Jul 13 19:09:05 mindpipe kernel:  [do_con_trol+2955/3408] do_con_trol+0xb8b/0xd50
Jul 13 19:09:05 mindpipe kernel:  [do_con_write+1071/1792] do_con_write+0x42f/0x700
Jul 13 19:09:05 mindpipe kernel:  [con_put_char+49/64] con_put_char+0x31/0x40
Jul 13 19:09:05 mindpipe kernel:  [opost+178/464] opost+0xb2/0x1d0
Jul 13 19:09:05 mindpipe kernel:  [write_chan+424/528] write_chan+0x1a8/0x210
Jul 13 19:09:05 mindpipe kernel:  [tty_write+247/448] tty_write+0xf7/0x1c0
Jul 13 19:09:05 mindpipe kernel:  [vfs_write+188/272] vfs_write+0xbc/0x110
Jul 13 19:09:05 mindpipe kernel:  [sys_write+46/80] sys_write+0x2e/0x50
Jul 13 19:09:05 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 19:09:05 mindpipe kernel:
Jul 13 19:09:05 mindpipe kernel:
Jul 13 19:09:05 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:09:05 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:09:05 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:09:05 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:09:05 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:09:05 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:09:05 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:09:05 mindpipe kernel:  [write_chan+382/528] write_chan+0x17e/0x210
Jul 13 19:09:05 mindpipe kernel:  [tty_write+247/448] tty_write+0xf7/0x1c0
Jul 13 19:09:05 mindpipe kernel:  [vfs_write+188/272] vfs_write+0xbc/0x110
Jul 13 19:09:05 mindpipe kernel:  [sys_write+46/80] sys_write+0x2e/0x50
Jul 13 19:09:05 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 19:09:05 mindpipe kernel:
Jul 13 19:09:06 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 19:09:06 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 19:09:06 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 19:09:06 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 19:09:06 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 19:09:06 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 19:09:06 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 19:09:06 mindpipe kernel:  [con_write+26/48] con_write+0x1a/0x30
Jul 13 19:09:06 mindpipe kernel:  [opost_block+231/416] opost_block+0xe7/0x1a0
Jul 13 19:09:06 mindpipe kernel:  [write_chan+382/528] write_chan+0x17e/0x210
Jul 13 19:09:06 mindpipe kernel:  [tty_write+247/448] tty_write+0xf7/0x1c0
Jul 13 19:09:06 mindpipe kernel:  [vfs_write+188/272] vfs_write+0xbc/0x110
Jul 13 19:09:06 mindpipe kernel:  [sys_write+46/80] sys_write+0x2e/0x50
Jul 13 19:09:06 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 19:09:06 mindpipe kernel:

The following XRUNS occurred during:

cp -ax /reiserfs-filesystem /ext3-filesystem

Jul 13 18:45:25 mindpipe kernel:
Jul 13 18:45:25 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 18:45:25 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 18:45:25 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 18:45:25 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 18:45:25 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:45:25 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:45:25 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:45:26 mindpipe kernel:  [ide_build_sglist+50/160] ide_build_sglist+0x32/0xa0
Jul 13 18:45:26 mindpipe kernel:  [ide_build_dmatable+372/400] ide_build_dmatable+0x174/0x190
Jul 13 18:45:26 mindpipe kernel:  [ide_start_dma+27/144] ide_start_dma+0x1b/0x90
Jul 13 18:45:26 mindpipe kernel:  [__ide_dma_write+39/176] __ide_dma_write+0x27/0xb0
Jul 13 18:45:26 mindpipe kernel:  [__ide_do_rw_disk+858/1408] __ide_do_rw_disk+0x35a/0x580
Jul 13 18:45:26 mindpipe kernel:  [start_request+313/544] start_request+0x139/0x220
Jul 13 18:45:26 mindpipe kernel:  [ide_do_request+494/880] ide_do_request+0x1ee/0x370
Jul 13 18:45:26 mindpipe kernel:  [ide_intr+302/416] ide_intr+0x12e/0x1a0
Jul 13 18:45:26 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:45:26 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:45:26 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:45:26 mindpipe kernel:  [cache_init_objs+104/128] cache_init_objs+0x68/0x80
Jul 13 18:45:26 mindpipe kernel:  [cache_grow+230/512] cache_grow+0xe6/0x200
Jul 13 18:45:26 mindpipe kernel:  [cache_alloc_refill+358/576] cache_alloc_refill+0x166/0x240
Jul 13 18:45:26 mindpipe kernel:  [kmem_cache_alloc+62/80] kmem_cache_alloc+0x3e/0x50
Jul 13 18:45:26 mindpipe kernel:  [alloc_buffer_head+20/80] alloc_buffer_head+0x14/0x50
Jul 13 18:45:26 mindpipe kernel:  [create_buffers+39/176] create_buffers+0x27/0xb0
Jul 13 18:45:26 mindpipe kernel:  [create_empty_buffers+23/160] create_empty_buffers+0x17/0xa0
Jul 13 18:45:26 mindpipe kernel:  [__block_prepare_write+817/896] __block_prepare_write+0x331/0x380
Jul 13 18:45:26 mindpipe kernel:  [block_prepare_write+31/64] block_prepare_write+0x1f/0x40
Jul 13 18:45:26 mindpipe kernel:  [generic_file_aio_write_nolock+976/2944] generic_file_aio_write_nolock+0x3d0/0xb80
Jul 13 18:45:26 mindpipe kernel:  [generic_file_write_nolock+109/144] generic_file_write_nolock+0x6d/0x90
Jul 13 18:45:26 mindpipe kernel:  [blkdev_file_write+37/48] blkdev_file_write+0x25/0x30
Jul 13 18:45:26 mindpipe kernel:  [vfs_write+188/272] vfs_write+0xbc/0x110
Jul 13 18:45:26 mindpipe kernel:  [sys_write+46/80] sys_write+0x2e/0x50
Jul 13 18:45:26 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 18:45:26 mindpipe kernel:
Jul 13 18:45:26 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 18:45:26 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 18:45:26 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 18:45:26 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 18:45:26 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:45:26 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:45:26 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:45:26 mindpipe kernel:  [ide_start_dma+27/144] ide_start_dma+0x1b/0x90
Jul 13 18:45:26 mindpipe kernel:  [__ide_dma_write+39/176] __ide_dma_write+0x27/0xb0
Jul 13 18:45:26 mindpipe kernel:  [__ide_do_rw_disk+858/1408] __ide_do_rw_disk+0x35a/0x580
Jul 13 18:45:26 mindpipe kernel:  [start_request+313/544] start_request+0x139/0x220
Jul 13 18:45:26 mindpipe kernel:  [ide_do_request+494/880] ide_do_request+0x1ee/0x370
Jul 13 18:45:26 mindpipe kernel:  [ide_intr+302/416] ide_intr+0x12e/0x1a0
Jul 13 18:45:26 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:45:26 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:45:26 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:45:26 mindpipe kernel:
Jul 13 18:45:26 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 18:45:26 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 18:45:26 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 18:45:26 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 18:45:26 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:45:26 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:45:26 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:45:26 mindpipe kernel:  [ide_build_sglist+50/160] ide_build_sglist+0x32/0xa0
Jul 13 18:45:26 mindpipe kernel:  [ide_build_dmatable+372/400] ide_build_dmatable+0x174/0x190
Jul 13 18:45:26 mindpipe kernel:  [ide_start_dma+27/144] ide_start_dma+0x1b/0x90
Jul 13 18:45:26 mindpipe kernel:  [__ide_dma_write+39/176] __ide_dma_write+0x27/0xb0
Jul 13 18:45:26 mindpipe kernel:  [__ide_do_rw_disk+858/1408] __ide_do_rw_disk+0x35a/0x580
Jul 13 18:45:26 mindpipe kernel:  [start_request+313/544] start_request+0x139/0x220
Jul 13 18:45:26 mindpipe kernel:  [ide_do_request+494/880] ide_do_request+0x1ee/0x370
Jul 13 18:45:26 mindpipe kernel:  [ide_intr+302/416] ide_intr+0x12e/0x1a0
Jul 13 18:45:26 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:45:26 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:45:26 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:45:26 mindpipe kernel:
Jul 13 18:45:26 mindpipe kernel: ALSA /usr/src/alsa-cvs-1.0.5/alsa-driver/alsa-kernel/core/pcm_lib.c:169: XRUN: pcmC0D0p
Jul 13 18:45:26 mindpipe kernel:  [dump_stack+23/32] dump_stack+0x17/0x20
Jul 13 18:45:26 mindpipe kernel:  [__crc_totalram_pages+1279533/3558647] snd_pcm_period_elapsed+0x2c7/0x400 [snd_pcm]
Jul 13 18:45:26 mindpipe kernel:  [__crc_totalram_pages+1463213/3558647] snd_emu10k1_interrupt+0x377/0x400 [snd_emu10k1]
Jul 13 18:45:26 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:45:26 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:45:26 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:45:26 mindpipe kernel:  [ide_build_sglist+50/160] ide_build_sglist+0x32/0xa0
Jul 13 18:45:26 mindpipe kernel:  [ide_build_dmatable+372/400] ide_build_dmatable+0x174/0x190
Jul 13 18:45:26 mindpipe kernel:  [ide_start_dma+27/144] ide_start_dma+0x1b/0x90
Jul 13 18:45:26 mindpipe kernel:  [__ide_dma_write+39/176] __ide_dma_write+0x27/0xb0
Jul 13 18:45:26 mindpipe kernel:  [__ide_do_rw_disk+858/1408] __ide_do_rw_disk+0x35a/0x580
Jul 13 18:45:26 mindpipe kernel:  [start_request+313/544] start_request+0x139/0x220
Jul 13 18:45:26 mindpipe kernel:  [ide_do_request+494/880] ide_do_request+0x1ee/0x370
Jul 13 18:45:26 mindpipe kernel:  [ide_intr+302/416] ide_intr+0x12e/0x1a0
Jul 13 18:45:26 mindpipe kernel:  [handle_IRQ_event+51/96] handle_IRQ_event+0x33/0x60
Jul 13 18:45:26 mindpipe kernel:  [do_IRQ+165/368] do_IRQ+0xa5/0x170
Jul 13 18:45:26 mindpipe kernel:  [common_interrupt+24/32] common_interrupt+0x18/0x20
Jul 13 18:45:26 mindpipe kernel:  [reiserfs_update_sd_size+105/416] reiserfs_update_sd_size+0x69/0x1a0
Jul 13 18:45:26 mindpipe kernel:  [reiserfs_dirty_inode+87/192] reiserfs_dirty_inode+0x57/0xc0
Jul 13 18:45:26 mindpipe kernel:  [reiserfs_submit_file_region_for_write+667/784] reiserfs_submit_file_region_for_write+0x29b/0x310
Jul 13 18:45:26 mindpipe kernel:  [reiserfs_file_write+1543/2032] reiserfs_file_write+0x607/0x7f0
Jul 13 18:45:26 mindpipe kernel:  [do_readv_writev+363/592] do_readv_writev+0x16b/0x250
Jul 13 18:45:26 mindpipe kernel:  [vfs_writev+68/80] vfs_writev+0x44/0x50
Jul 13 18:45:26 mindpipe kernel:  [sys_writev+46/80] sys_writev+0x2e/0x50
Jul 13 18:45:26 mindpipe kernel:  [syscall_call+7/11] syscall_call+0x7/0xb
Jul 13 18:45:26 mindpipe kernel:

Lee