pulseaudio reports asyncq overrun and ratelimit.c supressed messages

[Jeff Lane ]

Binary package hint: pulseaudio

This could be similar to bug 480802, however I didn't see any indication of the asyncq.c overrun messages, so I'm opening a new one.

I noticed this while looking into an Xorg crash on Karmic 64bit. In addition to the thousands of lines of ratelimit.c supressed messages, I noticed a few that said this:

Feb 28 23:01:45 klaatu pulseaudio[2034]: asyncq.c: q overrun, queuing locally
Feb 28 23:01:51 klaatu pulseaudio[2034]: last message repeated 10 times

See attached files for hardware specs.

This affects pulesaudio.
My expectations:
1: eliminate the flooding of syslogd wtih those ratelimit.c messages
2: understand the asyncq.c overrun messages

Audio can be somewhat choppy regardless of whether I'm streaming or playing files locally. I normally would expect that of a system running as many apps as I may be running at any given moment, however, on this system:

Core i7 Quadcore w/ HT - 16 cores total
4GB ram

I would really not expect choppy audio at all unless I was doing something incredibly extreme.

ProblemType: Bug
AplayDevices:
 **** List of PLAYBACK Hardware Devices ****
 card 0: Intel [HDA Intel], device 0: STAC92xx Analog [STAC92xx Analog]
   Subdevices: 1/1
   Subdevice #0: subdevice #0
Architecture: amd64
ArecordDevices:
 **** List of CAPTURE Hardware Devices ****
 card 0: Intel [HDA Intel], device 0: STAC92xx Analog [STAC92xx Analog]
   Subdevices: 2/2
   Subdevice #0: subdevice #0
   Subdevice #1: subdevice #1
AudioDevicesInUse:
 USER PID ACCESS COMMAND
 /dev/snd/controlC0: bladernr 12444 F.... pulseaudio
Card0.Amixer.info:
 Card hw:0 'Intel'/'HDA Intel at 0xf0f20000 irq 22'
   Mixer name : 'IDT 92HD83C1X5'
   Components : 'HDA:111d7604,102802a2,00100104'
   Controls : 28
   Simple ctrls : 17
CheckboxSubmission: 89874cc6062c150ee1cec9632b63a0a3
CheckboxSystem: 5f30ac82cc48ed91bb5240b61cb4e295
Date: Sun Feb 28 23:34:44 2010
DistroRelease: Ubuntu 9.10
InstallationMedia: Ubuntu 9.10 "Karmic Koala" - Release amd64 (20091027)
NonfreeKernelModules: nvidia
Package: pulseaudio 1:0.9.19-0ubuntu4.1
ProcEnviron:
 LANG=en_US.UTF-8
 SHELL=/bin/bash
ProcVersionSignature: Ubuntu 2.6.31-19.56-generic
SourcePackage: pulseaudio
Uname: Linux 2.6.31-19-generic x86_64
XsessionErrors:
 (gnome-settings-daemon:12459): GLib-CRITICAL **: g_propagate_error: assertion `src != NULL' failed
 (gnome-settings-daemon:12459): GLib-CRITICAL **: g_propagate_error: assertion `src != NULL' failed
 (polkit-gnome-authentication-agent-1:12574): GLib-CRITICAL **: g_once_init_leave: assertion `initialization_value != 0' failed
 (nautilus:12555): Eel-CRITICAL **: eel_preferences_get_boolean: assertion `preferences_is_initialized ()' failed
 (firefox:12718): GLib-WARNING **: g_set_prgname() called multiple times

[Daniel T Chen (crimsun)]

Please reproduce the symptom using the versions of pulseaudio and linux-alsa-driver-modules-2.6.31-19-generic from ppa:ubuntu-audio-dev

[Jeff Lane  (bladernr)]

The ratelimit message shows up, but only every once in a while. No where near like the flooding I was seeing before. Also the asyncq.c messages have gone completely since updating wtih the PPA.

[Jeff Lane  (bladernr)]

Should have added, I'm going to set it up to play mp3s all night, and then stream all day tomorrow and see if they show up in large numbers again. I'll update after tomorrow's test.

[Jeff Lane  (bladernr)]

So I'm not getting a flood of messages, but I do still get them:

Mar 9 23:40:01 klaatu pulseaudio[2213]: ratelimit.c: 5 events suppressed
Mar 9 23:58:57 klaatu pulseaudio[2213]: ratelimit.c: 4 events suppressed
Mar 10 00:10:30 klaatu pulseaudio[2213]: ratelimit.c: 2 events suppressed
Mar 10 00:30:58 klaatu pulseaudio[2213]: ratelimit.c: 5 events suppressed
Mar 10 01:07:59 klaatu pulseaudio[2213]: ratelimit.c: 7 events suppressed
Mar 10 01:09:25 klaatu pulseaudio[2213]: ratelimit.c: 13 events suppressed
Mar 10 04:24:20 klaatu pulseaudio[2213]: ratelimit.c: 2 events suppressed

so that's far more liveable than what I was getting before... that was from playing MP3s all night on rotation through rhythmbox. I'll switch over to streaming and see what happens now.

[Daniel T Chen (crimsun)]

To note, these sorts of messages generally are caused by poor sound drivers (or lack of a workaround being used in them for poor hardware). It isn't a bug in and of itself to see them occasionally. However, if you experience poor sound performance through PA, then *that* symptom generally does point to a bug somewhere in the audio stack (generally lower, closer to hw).

[Jeff Lane  (bladernr)]

Ok... so I can understand that seeing them occasionally is ok... in fact, after I tried that PPA before, on 9.10 64bit, I am now seeing them again in 10.04 64bit... like so:

Apr 8 14:00:05 klaatu pulseaudio[7573]: ratelimit.c: 1 events suppressed
Apr 8 14:00:10 klaatu pulseaudio[7573]: ratelimit.c: 35 events suppressed
Apr 8 14:00:22 klaatu pulseaudio[7573]: ratelimit.c: 128 events suppressed
Apr 8 14:00:28 klaatu pulseaudio[7573]: ratelimit.c: 67 events suppressed
Apr 8 14:00:37 klaatu pulseaudio[7573]: ratelimit.c: 64 events suppressed
Apr 8 14:00:47 klaatu pulseaudio[7573]: ratelimit.c: 149 events suppressed
Apr 8 14:01:04 klaatu pulseaudio[7573]: ratelimit.c: 39 events suppressed
Apr 8 14:01:12 klaatu pulseaudio[7573]: ratelimit.c: 149 events suppressed
Apr 8 14:01:34 klaatu pulseaudio[7573]: ratelimit.c: 3 events suppressed
Apr 8 14:01:41 klaatu pulseaudio[7573]: ratelimit.c: 200 events suppressed
Apr 8 14:01:50 klaatu pulseaudio[7573]: ratelimit.c: 374 events suppressed
Apr 8 14:01:59 klaatu pulseaudio[7573]: ratelimit.c: 13 events suppressed
Apr 8 14:02:24 klaatu pulseaudio[7573]: ratelimit.c: 96 events suppressed
Apr 8 14:02:29 klaatu pulseaudio[7573]: ratelimit.c: 138 events suppressed
Apr 8 14:02:43 klaatu pulseaudio[7573]: ratelimit.c: 8 events suppressed
Apr 8 14:02:49 klaatu pulseaudio[7573]: ratelimit.c: 28 events suppressed
Apr 8 14:03:00 klaatu pulseaudio[7573]: ratelimit.c: 212 events suppressed
Apr 8 14:03:05 klaatu pulseaudio[7573]: ratelimit.c: 74 events suppressed
Apr 8 14:03:39 klaatu pulseaudio[7573]: ratelimit.c: 48 events suppressed
Apr 8 14:04:17 klaatu pulseaudio[7573]: ratelimit.c: 82 events suppressed
Apr 8 14:04:26 klaatu pulseaudio[7573]: ratelimit.c: 63 events suppressed
Apr 8 14:04:33 klaatu pulseaudio[7573]: ratelimit.c: 48 events suppressed
Apr 8 14:04:40 klaatu pulseaudio[7573]: ratelimit.c: 29 events suppressed
Apr 8 14:04:45 klaatu pulseaudio[7573]: ratelimit.c: 5 events suppressed
Apr 8 14:05:28 klaatu pulseaudio[7573]: ratelimit.c: 74 events suppressed
Apr 8 14:05:34 klaatu pulseaudio[7573]: ratelimit.c: 9 events suppressed
Apr 8 14:06:08 klaatu pulseaudio[7573]: ratelimit.c: 18 events suppressed
Apr 8 14:06:15 klaatu pulseaudio[7573]: ratelimit.c: 75 events suppressed
Apr 8 14:06:23 klaatu pulseaudio[7573]: ratelimit.c: 22 events suppressed
Apr 8 14:06:51 klaatu pulseaudio[7573]: ratelimit.c: 12 events suppressed
Apr 8 14:07:01 klaatu pulseaudio[7573]: ratelimit.c: 81 events suppressed
Apr 8 14:07:08 klaatu pulseaudio[7573]: ratelimit.c: 66 events suppressed
Apr 8 14:07:23 klaatu pulseaudio[7573]: ratelimit.c: 24 events suppressed
Apr 8 14:09:17 klaatu pulseaudio[7573]: ratelimit.c: 133 events suppressed
Apr 8 14:09:51 klaatu pulseaudio[7573]: ratelimit.c: 103 events suppressed
Apr 8 14:09:57 klaatu pulseaudio[7573]: ratelimit.c: 105 events suppressed
Apr 8 14:10:04 klaatu pulseaudio[7573]: ratelimit.c: 152 events suppressed
Apr 8 14:10:11 klaatu pulseaudio[7573]: ratelimit.c: 35 events suppressed
Apr 8 14:10:18 klaatu pulseaudio[7573]: ratelimit.c: 110 events suppressed
Apr 8 14:10:24 klaatu pulseaudio[7573]: ratelimit.c: 168 events suppressed
Apr 8 14:10:32 k...

[Daniel T Chen (crimsun)]

We'll revisit this in 10.10. Closing for 10.04 LTS.

[cometdog (ericctharley)]

@ Daniel T Chen

Why closing for 10.04 LTS? Isn't an LTS version supposed to get support and updates for a long time? Do you need more information to troubleshoot?

I get one of these messages in my logs every 5 seconds while playing music.

[eluminx (eluminx-)]

i am also experiencing this bug, and unfortunately it will not let me play any music at all. It makes the stream skip every 5-7 seconds and also makes my mouse skip/get stuck until i press any key on the keyboard.

[Jeff Lane  (bladernr)]

Modifying the subject as Karmic is no longer active. But in case this is still an issue in active releases, it should remain open.

I should note that I have not noticed this in 11.04.

[Daniel van Vugt (vanvugt)]

Thank you for reporting this bug to Ubuntu.
Ubuntu 11.04 (natty) reached end-of-life on October 28, 2012.

See this document for currently supported Ubuntu releases:
https://wiki.ubuntu.com/Releases

Please upgrade to the latest version and re-test.

[Launchpad Janitor (janitor)]

[Expired for pulseaudio (Ubuntu) because there has been no activity for 60 days.]