alsa-util.c: snd_pcm_delay() returned a value that is exceptionally large: -4496252 bytes (-25488 ms).

Bug #464442 reported by arky
40
This bug affects 8 people
Affects Status Importance Assigned to Milestone
Linux
New
Undecided
Unassigned
alsa-driver (Ubuntu)
Incomplete
Undecided
Unassigned

Bug Description

Audio hanged while using espeak TTS to read a large chuck of text. While playing other parallel audio notifications. The quality of audio is proper with lot of static and abberrations

E: alsa-util.c: avail_min : 352
E: alsa-util.c: period_event : 1
E: alsa-util.c: start_threshold : -1
E: alsa-util.c: stop_threshold : 1845493760
E: alsa-util.c: silence_threshold: 0
E: alsa-util.c: silence_size : 0
E: alsa-util.c: boundary : 1845493760
E: alsa-util.c: Slave: Hardware PCM card 0 'HDA Intel' device 0 subdevice 0
E: alsa-util.c: Its setup is:
E: alsa-util.c: stream : PLAYBACK
E: alsa-util.c: access : MMAP_INTERLEAVED
E: alsa-util.c: format : S16_LE
E: alsa-util.c: subformat : STD
E: alsa-util.c: channels : 2
E: alsa-util.c: rate : 44100
E: alsa-util.c: exact rate : 44100 (44100/1)
E: alsa-util.c: msbits : 16
E: alsa-util.c: buffer_size : 3520
E: alsa-util.c: period_size : 352
E: alsa-util.c: period_time : 7981
E: alsa-util.c: tstamp_mode : ENABLE
E: alsa-util.c: period_step : 1
E: alsa-util.c: avail_min : 352
E: alsa-util.c: period_event : 1
E: alsa-util.c: start_threshold : -1
E: alsa-util.c: stop_threshold : 1845493760
E: alsa-util.c: silence_threshold: 0
E: alsa-util.c: silence_size : 0
E: alsa-util.c: boundary : 1845493760
E: alsa-util.c: appl_ptr : 18557793
E: alsa-util.c: hw_ptr : 19713488
E: alsa-util.c: snd_pcm_delay() returned a value that is exceptionally large: -4496252 bytes (-25488 ms).
E: 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-util.c: snd_pcm_dump():
E: alsa-util.c: Soft volume PCM
E: alsa-util.c: Control: PCM Playback Volume
E: alsa-util.c: min_dB: -51
E: alsa-util.c: max_dB: 0
E: alsa-util.c: resolution: 256
E: alsa-util.c: Its setup is:
E: alsa-util.c: stream : PLAYBACK
E: alsa-util.c: access : MMAP_INTERLEAVED
E: alsa-util.c: format : S16_LE
E: alsa-util.c: subformat : STD
E: alsa-util.c: channels : 2
E: alsa-util.c: rate : 44100
E: alsa-util.c: exact rate : 44100 (44100/1)
E: alsa-util.c: msbits : 16
E: alsa-util.c: buffer_size : 3520
E: alsa-util.c: period_size : 352
E: alsa-util.c: period_time : 7981
E: alsa-util.c: tstamp_mode : ENABLE
E: alsa-util.c: period_step : 1
E: alsa-util.c: avail_min : 352
E: alsa-util.c: period_event : 1
E: alsa-util.c: start_threshold : -1
E: alsa-util.c: stop_threshold : 1845493760
E: alsa-util.c: silence_threshold: 0
E: alsa-util.c: silence_size : 0
E: alsa-util.c: boundary : 1845493760
E: alsa-util.c: Slave: Hardware PCM card 0 'HDA Intel' device 0 subdevice 0
E: alsa-util.c: Its setup is:
E: alsa-util.c: stream : PLAYBACK
E: alsa-util.c: access : MMAP_INTERLEAVED
E: alsa-util.c: format : S16_LE
E: alsa-util.c: subformat : STD
E: alsa-util.c: channels : 2
E: alsa-util.c: rate : 44100
E: alsa-util.c: exact rate : 44100 (44100/1)
E: alsa-util.c: msbits : 16
E: alsa-util.c: buffer_size : 3520
E: alsa-util.c: period_size : 352
E: alsa-util.c: period_time : 7981
E: alsa-util.c: tstamp_mode : ENABLE
E: alsa-util.c: period_step : 1
E: alsa-util.c: avail_min : 352
E: alsa-util.c: period_event : 1
E: alsa-util.c: start_threshold : -1
E: alsa-util.c: stop_threshold : 1845493760
E: alsa-util.c: silence_threshold: 0
E: alsa-util.c: silence_size : 0
E: alsa-util.c: boundary : 1845493760
E: alsa-util.c: appl_ptr : 18589473
E: alsa-util.c: hw_ptr : 19713536
I: alsa-sink.c: Underrun!

ProblemType: Bug
Architecture: i386
ArecordDevices:
 **** List of CAPTURE Hardware Devices ****
 card 0: Intel [HDA Intel], device 0: CONEXANT Analog [CONEXANT Analog]
   Subdevices: 1/1
   Subdevice #0: subdevice #0
AudioDevicesInUse:
 USER PID ACCESS COMMAND
 /dev/snd/controlC0: iise 5349 F.... pulseaudio
 /dev/snd/pcmC0D0p: iise 5349 F...m pulseaudio
 /dev/snd/timer: iise 5349 f.... pulseaudio
Card0.Amixer.info:
 Card hw:0 'Intel'/'HDA Intel at 0xf6dfc000 irq 21'
   Mixer name : 'Conexant CX20561 (Hermosa)'
   Components : 'HDA:14f15051,10280298,00100000'
   Controls : 14
   Simple ctrls : 7
Date: Fri Oct 30 14:32:22 2009
DistroRelease: Ubuntu 9.10
Package: alsa-base 1.0.20+dfsg-1ubuntu5
PackageArchitecture: all
ProcEnviron:
 PATH=(custom, user)
 LANG=en_US.UTF-8
 SHELL=/bin/bash
ProcVersionSignature: Ubuntu 2.6.31-14.48-generic
SourcePackage: alsa-driver
Uname: Linux 2.6.31-14-generic i686

Revision history for this message
arky (arky) wrote :
Revision history for this message
arky (arky) wrote :

Might be related to [pulse] working around probably broken pause functionality, see http://www.pulseaudio.org/ticket/440

Revision history for this message
arky (arky) wrote :

$ pulseaudio -k

$ pulseaudio -vvv

I: main.c: Daemon startup complete.
D: module-udev-detect.c: /dev/snd/controlC0 is accessible: yes
I: client.c: Created 1 "Native client (UNIX socket client)"
D: protocol-native.c: Protocol version: remote 16, local 16
I: protocol-native.c: Got credentials: uid=1001 gid=1001 success=1
D: protocol-native.c: SHM possible: yes
D: protocol-native.c: Negotiated SHM: yes
D: module-augment-properties.c: Looking for .desktop file for gnome-settings-daemon
D: core-subscribe.c: Dropped redundant event due to change event.
I: client.c: Created 2 "Native client (UNIX socket client)"
D: protocol-native.c: Protocol version: remote 16, local 16
I: protocol-native.c: Got credentials: uid=1001 gid=1001 success=1
D: protocol-native.c: SHM possible: yes
D: protocol-native.c: Negotiated SHM: yes
D: module-augment-properties.c: Looking for .desktop file for gnome-volume-control-applet
E: alsa-source.c: ALSA woke us up to read new data from the device, but there was actually nothing to read!
E: alsa-source.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.c: We were woken up with POLLIN set -- however a subsequent snd_pcm_avail() returned 0 or another value < min_avail.

Revision history for this message
Brad Figg (brad-figg) wrote :

Hi arky,

Please, if you are still having issues, test with the latest development release of Ubuntu. ISO CD images are available from http://cdimage.ubuntu.com/releases/ .

If it remains an issue, could you run the following command from a Terminal (Applications->Accessories->Terminal). It will automatically gather and attach updated debug information to this report.

apport-collect -p alsa-base 464442

Also, if you could test the latest upstream kernel available that would be great. It will allow additional upstream developers to examine the issue. Refer to https://wiki.ubuntu.com/KernelMainlineBuilds .

Once you've tested the upstream kernel, please remove the 'needs-upstream-testing' tag. This can be done by clicking on the yellow pencil icon next to the tag located at the bottom of the bug description and deleting the 'needs-upstream-testing' text.

Please let us know your results.

    [This is an automated message. Apologies if it has reached you inappropriately; please just reply to this message indicating so.]

tags: added: kj-triage needs-required-logs needs-test-current-image
Changed in alsa-driver (Ubuntu):
status: New → Incomplete
Brad Figg (brad-figg)
tags: added: karmic
Revision history for this message
Thomas Guyot-Sionnest (dermoth) wrote :
Download full text (5.5 KiB)

I have similar problems after upgrading from Hardy to Lucid. Sound always stops after playing movies or using other applications for a few seconds/minutes regardless of the application used (I broke the sound with mplayer, vlc, totem and even the Multimedia System Selector app sound test).

Once broken, I can get sound back if I restart pulseaudio with:
$ pulseaudio --kill; pulseaudio --start

Sound always worked well in Hardy except for some glitches regarding sound control.

Each time sound breaks, I see this in syslog:

Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: snd_pcm_delay() returned a value that is exceptionally large: 8574853690509206496 bytes (360287948221 ms).
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: Most likely this is a bug in the ALSA driver 'snd_hda_intel'. Please report this issue to the ALSA developers.
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: snd_pcm_dump():
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: Soft volume PCM
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: Control: PCM Playback Volume
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: min_dB: -51
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: max_dB: 0
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: resolution: 256
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: Its setup is:
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: stream : CAPTURE
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: access : MMAP_INTERLEAVED
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: format : S16_LE
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: subformat : STD
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: channels : 2
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: rate : 48000
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: exact rate : 48000 (48000/1)
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: msbits : 16
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: buffer_size : 96000
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: period_size : 48000
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: period_time : 1000000
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: tstamp_mode : ENABLE
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: period_step : 1
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: avail_min : 48000
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: period_event : 0
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: start_threshold : -1
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: stop_threshold : 6755399441055744000
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: silence_threshold: 0
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: silence_size : 0
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: boundary : 6755399441055744000
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: Slave: Hardware PCM card 0 'HDA VIA VT82xx' device 0 subdevice 0
Apr 27 02:55:15 dermoth pulseaudio[32250]: alsa-util.c: Its setup is:
Apr 27 02:55:15 dermoth pu...

Read more...

tags: added: lucid
Changed in alsa-driver (Ubuntu):
status: Incomplete → New
Revision history for this message
aslam karachiwala (akwala) wrote :

Me too...

$ cat /proc/asound/version
Advanced Linux Sound Architecture Driver Version 1.0.22.1.
Compiled on Apr 29 2010 for kernel 2.6.32-22-generic (SMP).

Most likely related/duplicates: Bug #476652 & Bug #571464 -- I've posted logs to these.

Revision history for this message
aslam karachiwala (akwala) wrote :

Me too...

$ cat /proc/asound/version
Advanced Linux Sound Architecture Driver Version 1.0.22.1.
Compiled on Apr 29 2010 for kernel 2.6.32-22-generic (SMP).

Syslog excerpt corresponding to the "exceptionally large value" issue attached.

Revision history for this message
JB5 (sir-bunt) wrote :

Fresh Lucid Install (Even blanked /home)

jb@ERIC:~$ cat /proc/asound/version
Advanced Linux Sound Architecture Driver Version 1.0.21.
jb@ERIC:~$ uname -a
Linux ERIC 2.6.32-22-generic #33-Ubuntu SMP Wed Apr 28 13:28:05 UTC 2010 x86_64 GNU/Linux

Revision history for this message
aslam karachiwala (akwala) wrote :

JB5's log shows a different ALSA driver, which suggests that this occurs w/ >1 ALSA driver. So far the following drivers have been reported:
- snd_hda_intel
- snd_cmipci

Revision history for this message
Daniel T Chen (crimsun) wrote :

Please reproduce this symptom using the latest linux-alsa-driver-modules-$(uname -r) from ppa:ubuntu-audio-dev

Changed in alsa-driver (Ubuntu):
status: New → Incomplete
Revision history for this message
JB5 (sir-bunt) wrote :

OK added ppa

sudo add-apt-repository ppa:ubuntu-audio-dev/ppa

and installed module via synaptic

linux-alsa-driver-modules-2.6.32-22-generic

via synaptic
(NB There was no previously installed version of this module on my machine).

After a re-boot the system seems fine.
Excellent job. Thank-you.

Revision history for this message
aslam karachiwala (akwala) wrote :

I did the same as @JB5, and now I see...

$ cat /proc/asound/version
Advanced Linux Sound Architecture Driver Version 1.0.23.
Compiled on May 15 2010 for kernel 2.6.32-22-generic (SMP).

Have been playing audio via Quod Libet for >30 minutes and this problem hasn't occurred yet. Will report if it recurs.

======

However, I now see these recurring messages in syslog:

May 16 16:25:24 _name_ pulseaudio[4866]: ratelimit.c: 17 events suppressed
May 16 16:25:40 _name_ pulseaudio[4866]: ratelimit.c: 8 events suppressed
May 16 16:25:45 _name_ pulseaudio[4866]: ratelimit.c: 14 events suppressed
May 16 16:26:06 _name_ pulseaudio[4866]: ratelimit.c: 4 events suppressed
May 16 16:26:28 _name_ pulseaudio[4866]: ratelimit.c: 6 events suppressed
May 16 16:26:46 _name_ pulseaudio[4866]: ratelimit.c: 13 events suppressed
May 16 16:27:05 _name_ pulseaudio[4866]: ratelimit.c: 4 events suppressed
May 16 16:28:03 _name_ pulseaudio[4866]: ratelimit.c: 21 events suppressed

The behavior is reported in Bug #545065 -- the skipping/stuttering I hear is what the bug report describes as "jittery".

Revision history for this message
JB5 (sir-bunt) wrote :

Just to expand on my situation.

I also see the above ratelimit entries, see attached. So far not experienced a repeat of the original problem.

For completeness, I occasionally (Once every ca. 20-30mins) experience a small jump/skip forward (<=1 second) when listening to sound, either streamed via the internet or played locally through Exaile for example.

Not sure if this adds anything to bug Bug #545065 referenced above, certainly appears far less aggressive in my situation.

Let me know if I can provide any further info.

Revision history for this message
ybkosher (bobby-weinmann) wrote :

I had the same problem using snd_cmipci. Flash would play for a minute or two and then jerk for a second (like it couldn't keep up) and lose sound. Alternatively, mplayer would play for a minute or two and then freeze. I could restart either application and have sound for a minute or two.

I used the ppa as JB5 suggested and it's working now.

Revision history for this message
JB5 (sir-bunt) wrote :

Unfortunately today's upgrades seem to have brought the problem back.

cat /proc/asound/version
Advanced Linux Sound Architecture Driver Version 1.0.21.

uname -a
Linux ERIC 2.6.32-23-generic #37-Ubuntu SMP Fri Jun 11 08:03:28 UTC 2010 x86_64 GNU/Linux

Revision history for this message
JB5 (sir-bunt) wrote :

Hi - Still struggling with this problem.
None of the updated alsa-driver-modules (since 01-July-2010) have (Re)resolved the problem.

Not sure what info I can provide, or whether to open up a separate bug. Please let me know if I can do anything to help.

Once the error occurs, CPU usage ramps up and machine slows down. I either have to kill and then restart pulseaudio, or reload web-page (for instance) to regain control of machine, I can then try to play audio again.
This happens several times an hour.

Aside from using the updated alsa-driver-module, I tried out the tip from below in an attempt to cure the remaining stutter problem:-
http://www.ubuntugeek.com/fix-for-alsa-api-apps-in-ubuntu.html
However, makes no difference to stuttering or the meltdown of ALSA.

Thank you.

Revision history for this message
JB5 (sir-bunt) wrote :
Revision history for this message
JB5 (sir-bunt) wrote :
Revision history for this message
JB5 (sir-bunt) wrote :
Revision history for this message
Tarun Sharma (tarunks21) wrote :

Hi all, The following solution worked for me ....

sudo add-apt-repository ppa:ubuntu-audio-dev/ppa
uname -r
(This gave '2.6.32-24-generic' for me. You check yours and use it in the following line )
linux-alsa-driver-modules-2.6.32-24-generic

Now Restart

Thanks to all .........

Revision history for this message
Max (maxmax) wrote :

Issue also exists in a fully patched (11/11/2011) ubuntu 11.10.

(3.0.0-12-generic #20-Ubuntu SMP Fri Oct 7 14:50:42 UTC 2011 i686 i686 i386 GNU/Linux)

Revision history for this message
Boomer (gbmr) wrote :

Looks like it pretty old but also affects me.
Some details:
Lenovo G50-45 laptop.
3.13.0-46-generic #77-Ubuntu SMP Mon Mar 2 18:23:39 UTC 2015 x86_64 x86_64 x86_64 GNU/Linux
Sound delayed and some echo played when I changes sound volume. Only reboot helps to fix it but only for some time.
Usually sound breaks when I change volume level using Fn keys.

Let me know if you need additional details to investigate.

Thanks.

Revision history for this message
Boomer (gbmr) wrote :

Doesn't it affect anyone else?

Revision history for this message
svarog (varrkan82) wrote :
Download full text (6.4 KiB)

An issuue still present in Ubuntu 16.04.5.
```
[15:38:51]
[root@ThinkPad/var/log]#grep "alsa-sink-CX20751/2 Analog" syslog
Feb 27 14:58:50 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: Function snd_pcm_avail() returns a value that is extraordinarily large: 370196 байтів (2098 мс).
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 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.
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: snd_pcm_dump():
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: Soft volume PCM
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: Control: PCM Playback Volume
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: min_dB: -51
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: max_dB: 0
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: resolution: 256
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: Its setup is:
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: stream : PLAYBACK
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: access : MMAP_INTERLEAVED
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: format : S16_LE
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: subformat : STD
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: channels : 2
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: rate : 44100
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: exact rate : 44100 (44100/1)
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: msbits : 16
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: buffer_size : 16384
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: period_size : 8192
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: period_time : 185759
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: tstamp_mode : ENABLE
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: tstamp_type : MONOTONIC
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: period_step : 1
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: avail_min : 15680
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: period_event : 0
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: start_threshold : -1
Feb 27 14:58:51 ThinkPad pulseaudio[9613]: [alsa-sink-CX20751/2 Analog] alsa-util.c: stop_threshold : 4611686018427387904
Feb 27 14:58:5...

Read more...

description: updated
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.