Re: kern/51710: audio playback works only once

[Martin Husemann <martin%duskware.de@localhost>]

The following reply was made to PR kern/51710; it has been noted by GNATS.

From: Martin Husemann <martin%duskware.de@localhost>
To: gnats-bugs%NetBSD.org@localhost
Cc: 
Subject: Re: kern/51710: audio playback works only once
Date: Mon, 12 Dec 2016 13:13:20 +0100

 Here is dmesg with a audio debug kernel:
 
 autri0 at pci2 dev 8 function 0: Acer Labs M5451 AC-Link Controller Audio Device (rev. 0x02)
 autri0: interrupting at ivec 7a4
 autri0: ac97: Analog Devices AD1881A codec; headphone, Analog Devices Phat Stereo
 audioprobe: type=0 sa=0x2004fa8 hw=0x178c488
 audio0 at autri0: full duplex, playback, capture, mmap, independent
 audiosetinfo sc=0x108fc4000 ai=0x2004890
 audiosetinfo() Setting record params: enc=7 2ch 16/16bit 44100Hz
 audiosetinfo() Setting play params: enc=7 2ch 16/16bit 44100Hz
 audio_setup_pfilters: HW-buffer=0x108f04ac8 pustream=0x108f04848
 [0] enc=7 2ch 16/16bit 44100Hz
 [HW] enc=6 2ch 16/16bit 44100Hz
 audio_setup_rfilters: HW-buffer=0x108f04b68 pustream=0x108f04c38
 [HW] enc=6 2ch 16/16bit 44100Hz
 [0] enc=7 2ch 16/16bit 44100Hz
 audiosetinfo: filter setup is completed.
 audio_calc_blksize: record blksize=8820
 audio_calc_blksize: play blksize=8820
 audio_calc_blksize: record blksize=8820
 audio_calc_blksize: play blksize=8820
 audio_initbufs: mode=0x5
 audio_init_ringbuffer: MI blksize=8816
 audio_init_ringbuffer: final blksize=8816
 audio_init_ringbuffer: MI blksize=8816
 audio_init_ringbuffer: final blksize=8816
 audio_calcwater: plow=49152 phigh=65536 rlow=0 rhigh=56720
 audiostartp: start=0x25f9d2000 used=0(hi=65536 blk=8816) mmapped=0
 audiostartp: wakeup and return
 audiosetinfo sc=0x108fc4000 ai=0x2004890
 audiosetinfo() Setting record params: enc=7 2ch 16/16bit 44100Hz
 audiosetinfo() Setting play params: enc=7 2ch 16/16bit 44100Hz
 audio_setup_pfilters: HW-buffer=0x108f04ac8 pustream=0x108f04848
 [0] enc=7 2ch 16/16bit 44100Hz
 [HW] enc=6 2ch 16/16bit 44100Hz
 audio_setup_rfilters: HW-buffer=0x108f04b68 pustream=0x108f04c38
 [HW] enc=6 2ch 16/16bit 44100Hz
 [0] enc=7 2ch 16/16bit 44100Hz
 audiosetinfo: filter setup is completed.
 audio_calc_blksize: record blksize=8820
 audio_calc_blksize: play blksize=8820
 audio_calc_blksize: record blksize=8820
 audio_calc_blksize: play blksize=8820
 audio_initbufs: mode=0x5
 audio_init_ringbuffer: MI blksize=8816
 audio_init_ringbuffer: final blksize=8816
 audio_init_ringbuffer: MI blksize=8816
 audio_init_ringbuffer: final blksize=8816
 audiostartp: start=0x25f9d2000 used=0(hi=65536 blk=8816) mmapped=0
 audiostartp: wakeup and return
 audio_attach: inputs ports=0x7, input master=29, output ports=0x0, output master=3
 audioprobe: type=1 sa=0x2004fa8 hw=0x178c458
 
 
 When in bad state, a new try to play audio from userland looks like
 this:
 
 audio_open: flags=0x2 sc=0x108fc4000 hdl=0x107697b08
 audio_init_ringbuffer: MI blksize=8816
 audio_init_ringbuffer: final blksize=8816
 audio_init_ringbuffer: MI blksize=8816
 audio_init_ringbuffer: final blksize=8816
 audio_initbufs: mode=0x5
 audio_init_ringbuffer: MI blksize=8816
 audio_init_ringbuffer: final blksize=8816
 audio_init_ringbuffer: MI blksize=8816
 audio_init_ringbuffer: final blksize=8816
 audiosetinfo sc=0x108fc4000 ai=0x2606b7460
 audiosetinfo() Setting record params: enc=1 1ch 8/8bit 8000Hz
 audiosetinfo() Setting play params: enc=1 1ch 8/8bit 8000Hz
 audio_setup_pfilters: HW-buffer=0x1093452c8 pustream=0x109345048
 [0] enc=1 1ch 8/8bit 8000Hz
 [1] enc=7 1ch 16/16bit 8000Hz
 [HW] enc=7 2ch 16/16bit 44100Hz
 audio_setup_rfilters: HW-buffer=0x109345368 pustream=0x109345480
 [HW] enc=7 2ch 16/16bit 44100Hz
 [0] enc=7 1ch 16/16bit 8000Hz
 [1] enc=1 1ch 8/8bit 8000Hz
 audiosetinfo: filter setup is completed.
 audio_calc_blksize: record blksize=8820
 audio_calc_blksize: play blksize=8820
 audio_calc_blksize: record blksize=8820
 audio_calc_blksize: play blksize=8820
 audio_initbufs: mode=0x5
 audio_init_ringbuffer: MI blksize=8816
 audio_init_ringbuffer: final blksize=8816
 audio_calcwater: plow=49152 phigh=65536 rlow=0 rhigh=56716
 audiostartp: start=0x10b182000 used=0(hi=65536 blk=8816) mmapped=0
 audiostartp: wakeup and return
 audio_calcwater: plow=49152 phigh=65536 rlow=0 rhigh=56720
 audio_open: done sc_mode = 0x5
 audio_ioctl(136,'A',22)
 AUDIO_SETINFO mode=0x5
 audiosetinfo sc=0x108fc4000 ai=0x10b071f68
 audiosetinfo() Setting play params: enc=3 1ch 16/16bit 8000Hz
 audio_setup_pfilters: HW-buffer=0x1093452c8 pustream=0x109345048
 [0] enc=7 1ch 16/16bit 8000Hz
 [HW] enc=7 2ch 16/16bit 44100Hz
 audiosetinfo: filter setup is completed.
 audio_calc_blksize: record blksize=8820
 audio_calc_blksize: play blksize=8820
 audio_initbufs: mode=0x5
 audio_init_ringbuffer: MI blksize=8816
 audio_init_ringbuffer: final blksize=8816
 audio_calcwater: plow=49152 phigh=65536 rlow=0 rhigh=56716
 audio_ioctl(136,'A',22) result 0
 audio_ioctl(136,'A',22)
 AUDIO_SETINFO mode=0x5
 audiosetinfo sc=0x108fc4000 ai=0x10b071f68
 audio_ioctl(136,'A',22) result 0
 audio_close: sc=0x108fc4000
 audio_drain: enter busy=0
 audio_drain: n=0, used=0, drops=0
 
 ... here userland hangs
 
 and when I ^C the mpg123 process, it continues:
 
 mix_write: call trigger_output
 
 
 I have placed full (lenghty) audio debug output at
 	http://www.netbsd.org/~martin/audio.log
 
 
 Martin