snd_pcm_delay() returned a value that is exceptionally large:

Bug #374010 reported by Jarkko Lietolahti
30
This bug affects 5 people
Affects Status Importance Assigned to Milestone
pulseaudio (Ubuntu)
Confirmed
Undecided
Unassigned

Bug Description

Binary package hint: pulseaudio

Pulseaudio is misbehaving. Sometimes it works, sometimes it doesn't. Currently mostly it doesn't work that much. I've been trying workaround by starting pulseaudio manually (pulseaudio --start, killing it (pulseaudio --kill). Use the console (-C) and verbose mode, but this still remains a big annoyning mystery.jarkko@gandalf:~$ uname -a

I noticed that there're several bugs related to pulseaudio, from which some of are marked as fixed, but it seems there're more than one real bug.

Karmic
Linux gandalf 2.6.30-2-generic #3 SMP Mon May 4 23:40:22 EEST 2009 x86_64 GNU/Linux
jarkko@gandalf:~$

Revision history for this message
Jarkko Lietolahti (jarkko-jab) wrote :
Revision history for this message
Jarkko Lietolahti (jarkko-jab) wrote :

jarkko@gandalf:~$ pulseaudio
W: pid.c: Stale PID file, overwriting.
E: alsa-util.c: snd_pcm_avail() returned a value that is exceptionally large: 13835058055282163616 bytes (418293516410 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: Hooks PCM
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 : 3584
E: alsa-util.c: period_size : 448
E: alsa-util.c: period_time : 10158
E: alsa-util.c: tstamp_mode : ENABLE
E: alsa-util.c: period_step : 1
E: alsa-util.c: avail_min : 448
E: alsa-util.c: period_event : 0
E: alsa-util.c: start_threshold : -1
E: alsa-util.c: stop_threshold : 8070450532247928832
E: alsa-util.c: silence_threshold: 0
E: alsa-util.c: silence_size : 0
E: alsa-util.c: boundary : 8070450532247928832
E: alsa-util.c: Slave: Hardware PCM card 1 'HDA ATI HDMI' device 3 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 : 3584
E: alsa-util.c: period_size : 448
E: alsa-util.c: period_time : 10158
E: alsa-util.c: tstamp_mode : ENABLE
E: alsa-util.c: period_step : 1
E: alsa-util.c: avail_min : 448
E: alsa-util.c: period_event : 0
E: alsa-util.c: start_threshold : -1
E: alsa-util.c: stop_threshold : 8070450532247928832
E: alsa-util.c: silence_threshold: 0
E: alsa-util.c: silence_size : 0
E: alsa-util.c: boundary : 8070450532247928832
E: memblock.c: Assertion 'length' failed at pulsecore/memblock.c:356, function pa_memblock_new_fixed(). Aborting.

The last line is a new one.

Revision history for this message
Jarkko Lietolahti (jarkko-jab) wrote :

Testing with:
aplay /usr/share/sounds/alsa/*
Which almost always hangs during playback.
jarkko@gandalf:~$ aplay /usr/share/sounds/alsa/*
Toistetaan WAVE '/usr/share/sounds/alsa/Front_Center.wav' : Signed 16 bit Little Endian, Taajuus 48000 Hz, Mono
Toistetaan WAVE '/usr/share/sounds/alsa/Front_Left.wav' : Signed 16 bit Little Endian, Taajuus 48000 Hz, Mono
Toistetaan WAVE '/usr/share/sounds/alsa/Front_Right.wav' : Signed 16 bit Little Endian, Taajuus 48000 Hz, Mono
Toistetaan WAVE '/usr/share/sounds/alsa/Noise.wav' : Signed 16 bit Little Endian, Taajuus 48000 Hz, Mono
Toistetaan WAVE '/usr/share/sounds/alsa/Rear_Center.wav' : Signed 16 bit Little Endian, Taajuus 48000 Hz, Mono
Toistetaan WAVE '/usr/share/sounds/alsa/Rear_Left.wav' : Signed 16 bit Little Endian, Taajuus 48000 Hz, Mono
alitus!!! (vähintään 830,515 ms pitkä)
Toistetaan WAVE '/usr/share/sounds/alsa/Rear_Right.wav' : Signed 16 bit Little Endian, Taajuus 48000 Hz, Mono
^CSignaali Keskeytys keskeytti...

E: alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write!
E: alsa-sink.c: Most likely this is a bug in the ALSA driver 'snd_hda_intel'. Please report this issue to the ALSA developers.
E: alsa-sink.c: We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail() returned 0 or another value < min_avail.

module-combine.c: [Simultaneous output on HDA Intel] sample rates too different, not adjusting (44100 vs. 35858).

Revision history for this message
Jarkko Lietolahti (jarkko-jab) wrote :

Ops, missed these from pulseaudio output:
W: module-combine.c: [Simultaneous output on HDA Intel] sample rates too different, not adjusting (44100 vs. 8270).
W: module-combine.c: [Simultaneous output on HDA Intel] sample rates too different, not adjusting (44100 vs. 23481).
W: module-combine.c: [Simultaneous output on HDA Intel] sample rates too different, not adjusting (44100 vs. 33547).
W: module-combine.c: [Simultaneous output on HDA Intel] sample rates too different, not adjusting (44100 vs. 34507).
W: module-combine.c: [Simultaneous output on HDA Intel] sample rates too different, not adjusting (44100 vs. 35104).
W: module-combine.c: [Simultaneous output on HDA Intel] sample rates too different, not adjusting (44100 vs. 4294965178).
W: module-combine.c: [Simultaneous output on HDA Intel] sample rates too different, not adjusting (44100 vs. 10888).

Revision history for this message
Jarkko Lietolahti (jarkko-jab) wrote :

I: memblock.c: Pool full
I: memblock.c: Pool full
I: memblock.c: Pool full
I: memblock.c: Pool full
I: memblock.c: Pool full
I: memblock.c: Pool full

keeps repeating forever

Revision history for this message
Dave Gilbert (ubuntu-treblig) wrote :
Download full text (4.3 KiB)

Seen this also on Karmic:

(Note I'm running with option snd-hda-intel position_fix=2 )

May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: snd_pcm_avail() returned a value that is exceptionally large: 16140901064495857628 bytes (384307168202 ms).
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: Most likely this is a bug in the ALSA driver 'snd_hda_intel'. Please report this issue to the ALSA developers.
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: snd_pcm_dump():
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: Soft volume PCM
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: Control: PCM Playback Volume
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: min_dB: -51
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: max_dB: 0
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: resolution: 256
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: Its setup is:
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: stream : PLAYBACK
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: access : MMAP_INTERLEAVED
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: format : S16_LE
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: subformat : STD
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: channels : 2
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: rate : 48000
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: exact rate : 48000 (48000/1)
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: msbits : 16
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: buffer_size : 3840
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: period_size : 480
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: period_time : 10000
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: tstamp_mode : ENABLE
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: period_step : 1
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: avail_min : 480
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: period_event : 0
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: start_threshold : -1
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: stop_threshold : 8646911284551352320
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: silence_threshold: 0
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: silence_size : 0
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: boundary : 8646911284551352320
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: Slave: Hardware PCM card 0 'HDA Intel' device 0 subdevice 0
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: Its setup is:
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: stream : PLAYBACK
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: access : MMAP_INTERLEAVED
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: format : S16_LE
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: subformat : STD
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: channels : 2
May 25 12:21:39 davros pulseaudio[19156]: alsa-util.c: rate ...

Read more...

Changed in pulseaudio (Ubuntu):
status: New → Confirmed
Revision history for this message
Jared Wiltshire (jaredwiltshire) wrote :

Also getting the same thing with Realtek ALC889A onboard audio on a Gigabyte P35-DS3P motherboard.

Running self compiled 2.6.29 kernel on Ubuntu 9.04 64-bit.

Sometimes it works fine but more often than not the sound just plays really choppy. Changing between digital and analog output profiles also normally stuffs it up.

Revision history for this message
Jared Wiltshire (jaredwiltshire) wrote :

Oh yeh using Pulseaudio 0.9.15 from PPA.

Revision history for this message
Jared Wiltshire (jaredwiltshire) wrote :

This bug seems to be closely related to this bug reported in Fedora - https://bugzilla.redhat.com/show_bug.cgi?id=472339

Only it seems this is affecting people using the snd_hda_intel driver rather than snd-intel8x0

Seem the problem is probably related to the driver not pulseaudio as well..

Revision history for this message
Jared Wiltshire (jaredwiltshire) wrote :

As per http://www.pulseaudio.org/wiki/BrokenSoundDrivers it seems that the snd-intel-hda driver is broken. There is a patch for Fedora here https://bugzilla.redhat.com/show_bug.cgi?id=485734

Is this patch in any of the mainline kernels? How do we get this patch in Ubuntu?

Revision history for this message
Jared Wiltshire (jaredwiltshire) wrote :

OK I believe the patches are in the 2.6.30 series kernels or you can build the latest ALSA kernel drivers from http://www.alsa-project.org/main/index.php/Download

To build the ALSA kernel drivers you should just have to do

./configure --with-cards=hda-intel
make
sudo make install-modules

Revision history for this message
mlindeblom (mlindeblom) wrote :
Download full text (4.6 KiB)

I am running 2.6.30-7-generic #8-Ubuntu SMP Mon May 25 13:52:54 UTC 2009 x86_64 GNU/Linux

After rebooting, I unmute and adjust the volume level to max and still get no audio.

Issung "pkill pulseaudio; sleep 2; pulseaudio" give me a working audio for mplayer, vlc and rhythmbox.

Alsa output applications have a useless brief burble.

$ pkill pulseaudio; sleep 2; pulseaudio
E: alsa-util.c: snd_pcm_avail() returned a value that is exceptionally large: 16140901064495857112 bytes (384307168199 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: Hooks PCM
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 : 48000
E: alsa-util.c: exact rate : 48000 (48000/1)
E: alsa-util.c: msbits : 16
E: alsa-util.c: buffer_size : 3840
E: alsa-util.c: period_size : 480
E: alsa-util.c: period_time : 10000
E: alsa-util.c: tstamp_mode : ENABLE
E: alsa-util.c: period_step : 1
E: alsa-util.c: avail_min : 480
E: alsa-util.c: period_event : 0
E: alsa-util.c: start_threshold : -1
E: alsa-util.c: stop_threshold : 8646911284551352320
E: alsa-util.c: silence_threshold: 0
E: alsa-util.c: silence_size : 0
E: alsa-util.c: boundary : 8646911284551352320
E: alsa-util.c: Slave: Hardware PCM card 1 'HDA ATI HDMI' device 3 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 : 48000
E: alsa-util.c: exact rate : 48000 (48000/1)
E: alsa-util.c: msbits : 16
E: alsa-util.c: buffer_size : 3840
E: alsa-util.c: period_size : 480
E: alsa-util.c: period_time : 10000
E: alsa-util.c: tstamp_mode : ENABLE
E: alsa-util.c: period_step : 1
E: alsa-util.c: avail_min : 480
E: alsa-util.c: period_event : 0
E: alsa-util.c: start_threshold : -1
E: alsa-util.c: stop_threshold : 8646911284551352320
E: alsa-util.c: silence_threshold: 0
E: alsa-util.c: silence_size : 0
E: alsa-util.c: boundary : 8646911284551352320
E: alsa-util.c: appl_ptr : 111498
E: alsa-util.c: hw_ptr : 107520
E: alsa-util.c: snd_pcm_delay() returned a value that is exceptionally large: 2305843009213863464 bytes (882 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: Hooks PCM
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 : 48000
E: alsa-util.c: exact rate : 48000 (48000/1)
E: alsa-util.c: msbits : 16
E: alsa-util....

Read more...

Revision history for this message
Daniel T Chen (crimsun) wrote : Re: [Bug 374010] Re: snd_pcm_delay() returned a value that is exceptionally large:

Everyone,

For this behavior to be valid as a regression in karmic, you need to
be using 2.6.30-8.9-generic, not 2.6.30-7.8-generic.

On Fri, Jun 5, 2009 at 6:56 AM, mlindeblom<email address hidden> wrote:
> I am running 2.6.30-7-generic #8-Ubuntu SMP Mon May 25 13:52:54 UTC 2009
> x86_64 GNU/Linux

Also, please note that...

> After rebooting, I unmute and adjust the volume level to max and still
> get no audio.

...is a separate bug and thus should not be reported here.

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

The necessary patches are in karmic's 2.6.30-8.9-generic, but I'm
tracking a possible regression in one of the changesets.

On Tue, Jun 2, 2009 at 11:08 AM, Jared
Wiltshire<email address hidden> wrote:
> OK I believe the patches are in the 2.6.30 series kernels or you can
> build the latest ALSA kernel drivers from http://www.alsa-
> project.org/main/index.php/Download

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

jaunty-proposed's 2.6.28-13.xx-generic reverts a few of the added
changesets, because they cause severe regressions on several HDA
controllers (that can be hacked around via snd_hda_intel parameters
but certainly are not feasible from a default perspective).

On Tue, Jun 2, 2009 at 3:41 AM, Jared Wiltshire<email address hidden> wrote:
> This bug seems to be closely related to this bug reported in Fedora -
> https://bugzilla.redhat.com/show_bug.cgi?id=472339

Revision history for this message
mlindeblom (mlindeblom) wrote :

Confirmed 2.6.30-8-generic #9-Ubuntu SMP Wed Jun 3 15:38:38 UTC 2009 x86_64 GNU/Linux appears to solve this bug.

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.