[alsa-devel] snd_pcm_avail_delay() returned strange values: delay 0 is less than avail 4.

Adam Nielsen a.nielsen at shikadi.net
Sat Jun 27 09:36:17 CEST 2015


Hi all,

I'm trying to track down a problem with my media player (xmms2) where
it has stopped advancing to the next track after reaching the end of
the song.

I have the same distro with the same package versions on two slightly
different computers, and it works fine on one but not the other.  One
difference between the two is that although both have Intel HD Audio,
each is provided by a different type of chip.

I was asked to try pulseaudio to see if the problem persisted there and
it did, however upon loading the pulseaudio server it told me this:

E: [alsa-source-ALC892 Analog] alsa-util.c: snd_pcm_avail_delay() returned strange values: delay 0 is less than avail 4.
E: [alsa-source-ALC892 Analog] alsa-util.c: Most likely this is a bug in the ALSA driver 'snd_hda_intel'. Please report this issue to the ALSA developers.
E: [alsa-source-ALC892 Analog] alsa-util.c: snd_pcm_dump():
E: [alsa-source-ALC892 Analog] alsa-util.c: Direct Snoop PCM
E: [alsa-source-ALC892 Analog] alsa-util.c: Its setup is:
E: [alsa-source-ALC892 Analog] alsa-util.c:   stream       : CAPTURE
E: [alsa-source-ALC892 Analog] alsa-util.c:   access       : MMAP_INTERLEAVED
E: [alsa-source-ALC892 Analog] alsa-util.c:   format       : S32_LE
E: [alsa-source-ALC892 Analog] alsa-util.c:   subformat    : STD
E: [alsa-source-ALC892 Analog] alsa-util.c:   channels     : 2
E: [alsa-source-ALC892 Analog] alsa-util.c:   rate         : 48000
E: [alsa-source-ALC892 Analog] alsa-util.c:   exact rate   : 48000 (48000/1)
E: [alsa-source-ALC892 Analog] alsa-util.c:   msbits       : 32
E: [alsa-source-ALC892 Analog] alsa-util.c:   buffer_size  : 16384
E: [alsa-source-ALC892 Analog] alsa-util.c:   period_size  : 1024
E: [alsa-source-ALC892 Analog] alsa-util.c:   period_time  : 21333
E: [alsa-source-ALC892 Analog] alsa-util.c:   tstamp_mode  : ENABLE
E: [alsa-source-ALC892 Analog] alsa-util.c:   period_step  : 1
E: [alsa-source-ALC892 Analog] alsa-util.c:   avail_min    : 15943
E: [alsa-source-ALC892 Analog] alsa-util.c:   period_event : 0
E: [alsa-source-ALC892 Analog] alsa-util.c:   start_threshold  : -1
E: [alsa-source-ALC892 Analog] alsa-util.c:   stop_threshold   : 4611686018427387904
E: [alsa-source-ALC892 Analog] alsa-util.c:   silence_threshold: 0
E: [alsa-source-ALC892 Analog] alsa-util.c:   silence_size : 0
E: [alsa-source-ALC892 Analog] alsa-util.c:   boundary     : 4611686018427387904
E: [alsa-source-ALC892 Analog] alsa-util.c: Hardware PCM card 1 'HDA Intel PCH' device 0 subdevice 0
E: [alsa-source-ALC892 Analog] alsa-util.c: Its setup is:
E: [alsa-source-ALC892 Analog] alsa-util.c:   stream       : CAPTURE
E: [alsa-source-ALC892 Analog] alsa-util.c:   access       : MMAP_INTERLEAVED
E: [alsa-source-ALC892 Analog] alsa-util.c:   format       : S32_LE
E: [alsa-source-ALC892 Analog] alsa-util.c:   subformat    : STD
E: [alsa-source-ALC892 Analog] alsa-util.c:   channels     : 2
E: [alsa-source-ALC892 Analog] alsa-util.c:   rate         : 48000
E: [alsa-source-ALC892 Analog] alsa-util.c:   exact rate   : 48000 (48000/1)
E: [alsa-source-ALC892 Analog] alsa-util.c:   msbits       : 32
E: [alsa-source-ALC892 Analog] alsa-util.c:   buffer_size  : 16384
E: [alsa-source-ALC892 Analog] alsa-util.c:   period_size  : 1024
E: [alsa-source-ALC892 Analog] alsa-util.c:   period_time  : 21333
E: [alsa-source-ALC892 Analog] alsa-util.c:   tstamp_mode  : ENABLE
E: [alsa-source-ALC892 Analog] alsa-util.c:   period_step  : 1
E: [alsa-source-ALC892 Analog] alsa-util.c:   avail_min    : 1024
E: [alsa-source-ALC892 Analog] alsa-util.c:   period_event : 0
E: [alsa-source-ALC892 Analog] alsa-util.c:   start_threshold  : 1
E: [alsa-source-ALC892 Analog] alsa-util.c:   stop_threshold   : 4611686018427387904
E: [alsa-source-ALC892 Analog] alsa-util.c:   silence_threshold: 0
E: [alsa-source-ALC892 Analog] alsa-util.c:   silence_size : 0
E: [alsa-source-ALC892 Analog] alsa-util.c:   boundary     : 4611686018427387904
E: [alsa-source-ALC892 Analog] alsa-util.c:   appl_ptr     : 0
E: [alsa-source-ALC892 Analog] alsa-util.c:   hw_ptr       : 8236

So I am reporting this in the hope that someone understands what the
problem is and knows how to fix it.

The wiki said to include the output of aadebug with a bug report, so
here's that:

ALSA Audio Debug v0.2.0 - Sat Jun 27 17:26:44 AEST 2015
http://alsa.opensrc.org/aadebug
http://www.gnu.org/licenses/agpl-3.0.txt

Kernel ----------------------------------------------------
Linux korath.teln.shikadi.net 4.0.6-1-ARCH #1 SMP PREEMPT Tue Jun 23 14:25:08 CEST 2015 x86_64 GNU/Linux
Advanced Linux Sound Architecture Driver Version k4.0.6-1-ARCH.

Loaded Modules --------------------------------------------
snd_hrtimer            16384  1 
snd_seq                61440  1 
snd_seq_device         16384  1 snd_seq
snd_hda_codec_hdmi     53248  1 
snd_hda_codec_realtek    65536  1 
snd_hda_codec_generic    65536  1 snd_hda_codec_realtek
snd_hda_intel          28672  9 
snd_hda_controller     28672  1 snd_hda_intel
snd_hda_codec         114688  5 snd_hda_codec_realtek,snd_hda_codec_hdmi,snd_hda_codec_generic,snd_hda_intel,snd_hda_controller
snd_hwdep              16384  1 snd_hda_codec
snd_pcm                90112  6 snd_hda_codec_hdmi,snd_hda_codec,snd_hda_intel,snd_hda_controller
snd_timer              28672  5 snd_hrtimer,snd_pcm,snd_seq
snd                    69632  25 snd_hda_codec_realtek,snd_hwdep,snd_timer,snd_hda_codec_hdmi,snd_pcm,snd_seq,snd_hda_codec_generic,snd_hda_codec,snd_hda_intel,snd_seq_device

Proc Asound -----------------------------------------------
 0 [HDMI           ]: HDA-Intel - HDA Intel HDMI
                      HDA Intel HDMI at 0xf7c34000 irq 30
 1 [PCH            ]: HDA-Intel - HDA Intel PCH
                      HDA Intel PCH at 0xf7c30000 irq 28
  1:        : sequencer
  2: [ 1]   : control
  3: [ 1- 0]: digital audio playback
  4: [ 1- 0]: digital audio capture
  5: [ 1- 1]: digital audio playback
  6: [ 1- 2]: digital audio capture
  7: [ 1- 0]: hardware dependent
  8: [ 0]   : control
  9: [ 0- 3]: digital audio playback
 10: [ 0- 7]: digital audio playback
 11: [ 0- 0]: hardware dependent
 33:        : timer
01-00: HDA Codec 0
00-00: HDA Codec 0
00-03: HDMI 0 : HDMI 0 : playback 1
00-07: HDMI 1 : HDMI 1 : playback 1
01-00: ALC892 Analog : ALC892 Analog : playback 1 : capture 1
01-01: ALC892 Digital : ALC892 Digital : playback 1
01-02: ALC892 Alt Analog : ALC892 Alt Analog : capture 1
Client info
  cur  clients : 2
  peak clients : 2
  max  clients : 192

Client   0 : "System" [Kernel]
  Port   0 : "Timer" (Rwe-)
  Port   1 : "Announce" (R-e-)
Client  14 : "Midi Through" [Kernel]
  Port   0 : "Midi Through Port-0" (RWe-)
Client 128 : "TiMidity" [User]
  Port   0 : "TiMidity port 0" (-We-)
  Port   1 : "TiMidity port 1" (-We-)
  Port   2 : "TiMidity port 2" (-We-)
  Port   3 : "TiMidity port 3" (-We-)
  Output pool :
    Pool size          : 500
    Cells in use       : 0
    Peak cells in use  : 0
    Alloc success      : 0
    Alloc failures     : 0
  Input pool :
    Pool size          : 1000
    Cells in use       : 0
    Peak cells in use  : 0
    Alloc success      : 0
    Alloc failures     : 0

Dev Snd ---------------------------------------------------
total 0
drwxr-xr-x  2 root root       80 Jun 27 13:31 by-path
crw-rw----+ 1 root audio 116,  8 Jun 27 13:31 controlC0
crw-rw----+ 1 root audio 116,  2 Jun 27 13:31 controlC1
crw-rw----+ 1 root audio 116, 11 Jun 27 13:31 hwC0D0
crw-rw----+ 1 root audio 116,  7 Jun 27 13:31 hwC1D0
crw-rw----+ 1 root audio 116,  9 Jun 27 17:12 pcmC0D3p
crw-rw----+ 1 root audio 116, 10 Jun 27 17:12 pcmC0D7p
crw-rw----+ 1 root audio 116,  4 Jun 27 17:12 pcmC1D0c
crw-rw----+ 1 root audio 116,  3 Jun 27 17:13 pcmC1D0p
crw-rw----+ 1 root audio 116,  5 Jun 27 13:31 pcmC1D1p
crw-rw----+ 1 root audio 116,  6 Jun 27 13:31 pcmC1D2c
crw-rw----+ 1 root audio 116,  1 Jun 27 13:31 seq
crw-rw----+ 1 root audio 116, 33 Jun 27 13:31 timer

CPU -------------------------------------------------------
model name      : Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
cpu MHz         : 3097.500
model name      : Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
cpu MHz         : 3000.000
model name      : Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
cpu MHz         : 3295.664
model name      : Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
cpu MHz         : 3120.703
model name      : Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
cpu MHz         : 3001.406
model name      : Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
cpu MHz         : 3000.351
model name      : Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
cpu MHz         : 3645.585
model name      : Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
cpu MHz         : 3000.468

RAM -------------------------------------------------------
MemTotal:       16362900 kB
SwapTotal:             0 kB

Hardware --------------------------------------------------
00:02.0 VGA compatible controller: Intel Corporation Xeon E3-1200 v3/4th Gen Core Processor Integrated Graphics Controller (rev 06)
00:03.0 Audio device: Intel Corporation Xeon E3-1200 v3/4th Gen Core Processor HD Audio Controller (rev 06)
00:1b.0 Audio device: Intel Corporation 8 Series/C220 Series Chipset High Definition Audio Controller (rev 04)

Interupts -------------------------------------------------
           CPU0       CPU1       CPU2       CPU3       CPU4       CPU5       CPU6       CPU7
  0:         19          0          0          0          0          0          0          0   IO-APIC-edge      timer
  1:          1          0          0          0          0          0          0          1   IO-APIC-edge      i8042
  7:          9          0          0          0          0          0          0          0   IO-APIC-edge
  8:          0          0          0          1          0          0          0          0   IO-APIC-edge      rtc0
  9:          1          0          0          0          1          0          0          1   IO-APIC-fasteoi   acpi
 16:         20          0          0          0          4          1          3          1   IO-APIC  16-fasteoi   ehci_hcd:usb1
 23:         23          0          5          0          2          1          1          1   IO-APIC  23-fasteoi   ehci_hcd:usb2
 24:     236257      13391      10344       8635       7919       6578       6237       5429   PCI-MSI-edge      xhci_hcd
 25:      27200       5286       3884       3536      10293       3395       2433       1864   PCI-MSI-edge      0000:00:1f.2
 26:          3          6          0          0          0          2          0          2   PCI-MSI-edge      mei_me
 27:     231790      38059      33089      33480      38333      18853      15356      13578   PCI-MSI-edge      eth0
 28:     549701      20320      18918      16545      17439      14054      12933      11245   PCI-MSI-edge      snd_hda_intel
 29:       1936        799        524        368        520        541        356        265   PCI-MSI-edge      i915
 30:         25        272         13          2        477        200          8          2   PCI-MSI-edge      snd_hda_intel
NMI:         15         14         14         14          9          7          9         10   Non-maskable interrupts
LOC:    1051971     756503     703396     726403     198333     221752     198703     213191   Local timer interrupts
SPU:          0          0          0          0          0          0          0          0   Spurious interrupts
PMI:         15         14         14         14          9          7          9         10   Performance monitoring interrupts
IWI:          0          0          0          0          0          0          1          0   IRQ work interrupts
RTR:          7          0          0          0          0          0          0          0   APIC ICR read retries
RES:      13573      10138       7672       8132       3954       3624       3500       2935   Rescheduling interrupts
CAL:      32694      13897      13827      14113        968       1025       1057       1107   Function call interrupts
TLB:       7503       7559       9116      13064       9259       6773       5330       4805   TLB shootdowns
TRM:          0          0          0          0          0          0          0          0   Thermal event interrupts
THR:          0          0          0          0          0          0          0          0   Threshold APIC interrupts
MCE:          0          0          0          0          0          0          0          0   Machine check exceptions
MCP:         48         48         48         48         48         48         48         48   Machine check polls
HYP:          0          0          0          0          0          0          0          0   Hypervisor callback interrupts
ERR:          9
MIS:          0

Please let me know if I can provide any other info.

Many thanks,
Adam.



More information about the Alsa-devel mailing list