Bug 597502

Summary:	pulseaudio full of glitch misery after Fedora 12 upgrade
Product:	[Fedora] Fedora	Reporter:	David Anderson <fedora-packaging2>
Component:	pulseaudio	Assignee:	Lennart Poettering <lpoetter>
Status:	CLOSED WONTFIX	QA Contact:	Fedora Extras Quality Assurance <extras-qa>
Severity:	high	Docs Contact:
Priority:	medium
Version:	12	CC:	lkundrak, lpoetter, tihokibertron
Target Milestone:	---
Target Release:	---
Hardware:	All
OS:	Linux
Whiteboard:
Fixed In Version:		Doc Type:	Bug Fix
Doc Text:		Story Points:	---
Clone Of:		Environment:
Last Closed:	2010-12-03 14:13:38 UTC	Type:	---
Regression:	---	Mount Type:	---
Documentation:	---	CRM:
Verified Versions:		Category:	---
oVirt Team:	---	RHEL 7.3 requirements from Atomic Host:
Cloudforms Team:	---	Target Upstream Version:
Embargoed:

Description David Anderson 2010-05-29 08:42:03 UTC

I upgraded from Fedora 9 to Fedora 12 in January.

There always used to be the odd glitch, but now there are so many. If I play an MP3 in totem, with nothing else at all running (empty GNOME desktop), then every 15 seconds or so there is a half-second pause.

I "upgraded" last week to pulseaudio-0.9.21-5.fc12.x86_64 and I'd say that the problem got worse, though I don't have objective data. But it's barely usable - not much fun to listen to a track that gets glitched every 15 seconds or so. This never used to happen with ALSA. I'm using a dual-core AMD TL-50 and the system load is around 0.10.

I've changed nothing from any defaults of the Fedora 12 install.

Here are loaded kernel modules that look relevant:
snd_hda_codec_idt
snd_hda_intel
snd_hda_codec
snd_hwdep
snd_seq
snd_seq_device
snd_pcm
snd_timer
snd
soundcore
snd_page_alloc

I don't know if any of the sample of log messages are relevant:
May 25 18:54:14 localhost rtkit-daemon[1680]: Sucessfully made thread 1690 of process 1678 (/usr/bin/pulseaudio) owned by '500' RT at priority 5.
May 25 18:54:19 localhost rtkit-daemon[1680]: Sucessfully made thread 1864 of process 1864 (/usr/bin/pulseaudio) owned by '500' high priority at nice level -11.
May 25 18:54:20 localhost pulseaudio[1864]: pid.c: Daemon already running.
May 25 18:54:20 localhost rtkit-daemon[1680]: Sucessfully made thread 1945 of process 1945 (/usr/bin/pulseaudio) owned by '500' high priority at nice level -11.
May 25 18:54:20 localhost pulseaudio[1945]: pid.c: Daemon already running.
May 26 08:51:04 localhost rtkit-daemon[1680]: Successfully demoted thread 1685 of process 1678 (/usr/bin/pulseaudio).
May 26 08:51:04 localhost rtkit-daemon[1680]: Successfully demoted thread 1690 of process 1678 (/usr/bin/pulseaudio).
May 26 13:02:02 localhost pulseaudio[1678]: ratelimit.c: 409 events suppressed
May 26 13:08:21 localhost pulseaudio[1678]: ratelimit.c: 721 events suppressed
May 26 14:39:31 localhost pulseaudio[1678]: ratelimit.c: 409 events suppressed
May 26 16:00:14 localhost pulseaudio[1678]: ratelimit.c: 998 events suppressed
May 26 16:04:38 localhost pulseaudio[1678]: ratelimit.c: 1052 events suppressed

Those "events suppressed" messages don't coincide with the glitches - as I say, the glitches are coming much more frequently than that.

Comment 1 David Anderson 2010-05-29 09:20:25 UTC

I should have clarified that this does not happen all the time... only when pulseaudio wants to hate me. Whether it happens or not is not related to the system load - it can happen on the system when nothing else is running, and not happen when loads of things are running....

Comment 2 Tihomir 2010-07-04 19:56:15 UTC

Linux localhost.localdomain 2.6.32.14-127.fc12.x86_64 #1 SMP Fri May 28 04:30:39 UTC 2010 x86_64 x86_64 x86_64 GNU/Linux
Loaded plugins: refresh-packagekit
Installed Packages
Name       : pulseaudio
Arch       : x86_64
Version    : 0.9.21
Release    : 5.fc12
Size       : 1.9 M
Repo       : installed
From repo  : updates
Summary    : Improved Linux Sound Server
URL        : http://pulseaudio.org/
License    : LGPLv2+
Description: PulseAudio is a sound server for Linux and other Unix like
           : operating systems. It is intended to be an improved drop-in
           : replacement for the Enlightened Sound Daemon (ESOUND).

Jul  4 15:31:12 localhost pulseaudio[27390]: ratelimit.c: 111 events suppressed
Jul  4 15:31:19 localhost pulseaudio[27390]: ratelimit.c: 108 events suppressed
Jul  4 15:35:04 localhost pulseaudio[27390]: ratelimit.c: 106 events suppressed
Jul  4 15:36:03 localhost pulseaudio[27390]: ratelimit.c: 898 events suppressed
Jul  4 15:36:39 localhost pulseaudio[27390]: ratelimit.c: 898 events suppressed
Jul  4 15:46:14 localhost pulseaudio[27390]: ratelimit.c: 558 events suppressed
Jul  4 15:47:07 localhost pulseaudio[27390]: ratelimit.c: 72 events suppressed
Jul  4 15:47:34 localhost pulseaudio[27390]: ratelimit.c: 107 events suppressed
Jul  4 17:38:52 localhost rtkit-daemon[1996]: Sucessfully made thread 1994 of process 1994 (/usr/bin/pulseaudio) owned by '500' high priority at nice level -11.
Jul  4 17:39:00 localhost rtkit-daemon[1996]: Sucessfully made thread 2208 of process 2208 (/usr/bin/pulseaudio) owned by '500' high priority at nice level -11.
Jul  4 17:39:00 localhost pulseaudio[2208]: pid.c: Daemon already running.
Jul  4 17:39:00 localhost rtkit-daemon[1996]: Sucessfully made thread 2210 of process 2210 (/usr/bin/pulseaudio) owned by '500' high priority at nice level -11.
Jul  4 17:39:00 localhost pulseaudio[2210]: pid.c: Daemon already running.
Jul  4 17:41:47 localhost pulseaudio[1994]: ratelimit.c: 874 events suppressed
Jul  4 17:42:41 localhost pulseaudio[1994]: ratelimit.c: 882 events suppressed
Jul  4 17:49:42 localhost pulseaudio[1994]: ratelimit.c: 873 events suppressed
Jul  4 17:50:17 localhost pulseaudio[1994]: ratelimit.c: 879 events suppressed
Jul  4 17:50:40 localhost pulseaudio[1994]: ratelimit.c: 878 events suppressed
Jul  4 17:54:15 localhost pulseaudio[1994]: ratelimit.c: 881 events suppressed
Jul  4 17:56:22 localhost pulseaudio[1994]: ratelimit.c: 883 events suppressed
Jul  4 17:57:35 localhost pulseaudio[1994]: ratelimit.c: 523 events suppressed
Jul  4 18:04:05 localhost pulseaudio[1994]: ratelimit.c: 1876 events suppressed
Jul  4 18:09:39 localhost rtkit-daemon[1996]: Sucessfully made thread 2930 of process 2930 (/usr/bin/pulseaudio) owned by '500' high priority at nice level -11.
Jul  4 18:17:33 localhost pulseaudio[2930]: alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write!
Jul  4 18:17:33 localhost pulseaudio[2930]: alsa-sink.c: Most likely this is a bug in the ALSA driver 'snd_hda_intel'. Please report this issue to the ALSA developers.
Jul  4 18:17:33 localhost pulseaudio[2930]: alsa-sink.c: We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail() returned 0 or another value < min_avail.
Jul  4 20:25:12 localhost rtkit-daemon[2054]: Sucessfully made thread 2052 of process 2052 (/usr/bin/pulseaudio) owned by '500' high priority at nice level -11.
Jul  4 20:25:13 localhost rtkit-daemon[2054]: Sucessfully made thread 2127 of process 2127 (/usr/bin/pulseaudio) owned by '500' high priority at nice level -11.
Jul  4 20:25:13 localhost pulseaudio[2127]: pid.c: Daemon already running.
Jul  4 20:25:13 localhost rtkit-daemon[2054]: Sucessfully made thread 2129 of process 2129 (/usr/bin/pulseaudio) owned by '500' high priority at nice level -11.
Jul  4 20:25:13 localhost pulseaudio[2129]: pid.c: Daemon already running.
Jul  4 20:25:17 localhost pulseaudio[2052]: alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write!
Jul  4 20:25:17 localhost pulseaudio[2052]: alsa-sink.c: Most likely this is a bug in the ALSA driver 'snd_hda_intel'. Please report this issue to the ALSA developers.
Jul  4 20:25:17 localhost pulseaudio[2052]: alsa-sink.c: We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail() returned 0 or another value < min_avail.
#Experiencing crackling, periodic, when playing music.

Comment 3 Bug Zapper 2010-11-03 13:54:45 UTC

This message is a reminder that Fedora 12 is nearing its end of life.
Approximately 30 (thirty) days from now Fedora will stop maintaining
and issuing updates for Fedora 12.  It is Fedora's policy to close all
bug reports from releases that are no longer maintained.  At that time
this bug will be closed as WONTFIX if it remains open with a Fedora 
'version' of '12'.

Package Maintainer: If you wish for this bug to remain open because you
plan to fix it in a currently maintained version, simply change the 'version' 
to a later Fedora version prior to Fedora 12's end of life.

Bug Reporter: Thank you for reporting this issue and we are sorry that 
we may not be able to fix it before Fedora 12 is end of life.  If you 
would still like to see this bug fixed and are able to reproduce it 
against a later version of Fedora please change the 'version' of this 
bug to the applicable version.  If you are unable to change the version, 
please add a comment here and someone will do it for you.

Although we aim to fix as many bugs as possible during every release's 
lifetime, sometimes those efforts are overtaken by events.  Often a 
more recent Fedora release includes newer upstream software that fixes 
bugs or makes them obsolete.

The process we are following is described here: 
http://fedoraproject.org/wiki/BugZappers/HouseKeeping

Comment 4 Bug Zapper 2010-12-03 14:13:38 UTC

Fedora 12 changed to end-of-life (EOL) status on 2010-12-02. Fedora 12 is 
no longer maintained, which means that it will not receive any further 
security or bug fix updates. As a result we are closing this bug.

If you can reproduce this bug against a currently maintained version of 
Fedora please feel free to reopen this bug against that version.

Thank you for reporting this bug and we are sorry it could not be fixed.