From owner-freebsd-current@FreeBSD.ORG Thu Aug 19 00:25:22 2004 Return-Path: Delivered-To: freebsd-current@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id F391C16A4D3; Thu, 19 Aug 2004 00:25:21 +0000 (GMT) Received: from lakermmtao07.cox.net (lakermmtao07.cox.net [68.230.240.32]) by mx1.FreeBSD.org (Postfix) with ESMTP id 81DFA43D1F; Thu, 19 Aug 2004 00:25:14 +0000 (GMT) (envelope-from conrads@cox.net) Received: from dolphin.local.net ([68.11.71.51]) by lakermmtao07.cox.net (InterMail vM.6.01.03.02.01 201-2131-111-104-103-20040709) with ESMTP id <20040819002504.UNJH1823.lakermmtao07.cox.net@dolphin.local.net>; Wed, 18 Aug 2004 20:25:04 -0400 Received: from dolphin.local.net (localhost.local.net [127.0.0.1]) by dolphin.local.net (8.13.1/8.13.1) with SMTP id i7J0P4dw022044; Wed, 18 Aug 2004 19:25:04 -0500 (CDT) (envelope-from conrads@cox.net) Date: Wed, 18 Aug 2004 19:24:58 -0500 From: "Conrad J. Sabatier" To: freebsd-current@freebsd.org Message-Id: <20040818192458.7ee6d787@dolphin.local.net> X-Mailer: Sylpheed-Claws 0.9.12 (GTK+ 1.2.10; amd64-portbld-freebsd5.2) Mime-Version: 1.0 Content-Type: text/plain; charset=US-ASCII Content-Transfer-Encoding: 7bit cc: cg@freebsd.org Subject: pcm timeout (debug output from snd_ich driver) X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list List-Id: Discussions about the use of FreeBSD-current List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Thu, 19 Aug 2004 00:25:22 -0000 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 -- "In Unix veritas"