pcm timeout (debug output from snd_ich driver)

From: Conrad J. Sabatier <conrads_at_cox.net>
Date: Wed, 18 Aug 2004 19:24:58 -0500
OK, I enabled debug output in the sound driver in the hopes of finding
out a little bit more about what's going on with these insufferable pcm
timeouts using the snd_ich driver.

The following log info starts shortly before the timeout occurred during
the last "successful" run of madplay (i.e., the one that was running
when the timeout occurred), and includes a little bit of the next run of
madplay as well (which I interrupted with CTRL-C just after it began).

Hopefully there's something here (the flags and/or buffer sizes in
chn_flush?) that may offer a clue to someone more intimately familiar
with the workings of the sound drivers.

Perhaps we need to add even more debugging statements?  I really want to
get this thing debugged, folks!  The several-times-a-day reboots just to
get sound going again are really getting tiresome.

Aug 18 19:02:36 dolphin kernel: chn_setblocksize(0, 0)
Aug 18 19:02:36 dolphin kernel: chn_setblocksize: defaulting to(512,256)
Aug 18 19:02:36 dolphin kernel: chn_setblocksize: soft bps 2, spd 8000,
irqhz == 62
Aug 18 19:02:36 dolphin kernel: chn_setblocksize: hard blksz requested
512 (maxsize 16384), got 512, irqhz == 62
Aug 18 19:02:36 dolphin kernel: setspeed done, r = 0
Aug 18 19:02:36 dolphin kernel: want format 268435472
Aug 18 19:02:36 dolphin kernel: feederflags 0
Aug 18 19:02:36 dolphin kernel: setspeed, channel pcm0:play:0
Aug 18 19:02:36 dolphin kernel: want speed 8000, try speed 8000, got
speed 8000
Aug 18 19:02:36 dolphin kernel: feederflags 0
Aug 18 19:02:36 dolphin kernel: r = 0
Aug 18 19:02:36 dolphin kernel: chn_setblocksize(0, 0)
Aug 18 19:02:36 dolphin kernel: chn_setblocksize: defaulting to
(256,512)
Aug 18 19:02:36 dolphin kernel: chn_setblocksize: soft bps 4, spd 8000,
irqhz == 62
Aug 18 19:02:36 dolphin kernel: chn_setblocksize: hard blksz requested
512 (maxsize 16384), got 512, irqhz == 62
Aug 18 19:02:36 dolphin kernel: setspeed done, r = 0
Aug 18 19:02:36 dolphin kernel: setspeed, channel pcm0:play:0
Aug 18 19:02:36 dolphin kernel: want speed 44100, try speed 44100, got
speed 44100
Aug 18 19:02:36 dolphin kernel: feederflags 0
Aug 18 19:02:36 dolphin kernel: r = 0
Aug 18 19:02:36 dolphin kernel: chn_setblocksize(0, 0)
Aug 18 19:02:36 dolphin kernel: chn_setblocksize: defaulting to
(32,4096)
Aug 18 19:02:36 dolphin kernel: chn_setblocksize: soft bps 4, spd 44100,
irqhz == 43
Aug 18 19:02:36 dolphin kernel: chn_setblocksize: hard blksz requested
4096 (maxsize 16384), got 4096, irqhz == 43
Aug 18 19:02:36 dolphin kernel: setspeed done, r = 0
Aug 18 19:02:37 dolphin kernel: pcm0:play:0: play interrupt timeout,
channel dead
Aug 18 19:02:37 dolphin kernel: chn_flush: c->flags 0x00021030
Aug 18 19:02:38 dolphin kernel: chn_flush: timeout, hw 16384, sw 131072
Aug 18 19:02:38 dolphin kernel: chn_setblocksize(0, 0)
Aug 18 19:02:38 dolphin kernel: chn_setblocksize: defaulting to (32,
4096)
Aug 18 19:02:38 dolphin kernel: chn_setblocksize: soft bps 4, spd 44100,
irqhz == 43
Aug 18 19:02:38 dolphin kernel: chn_setblocksize: hard blksz requested
4096 (maxsize 16384), got 4096, irqhz == 43

-- 
Conrad J. Sabatier <conrads_at_cox.net> -- "In Unix veritas"
Received on Wed Aug 18 2004 - 22:25:22 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:38:07 UTC