[pulseaudio-discuss] gnome-shell hangs, waiting for pulse-audio

Henrik /KaarPoSoft henrik at kaarposoft.dk
Tue Nov 27 17:35:47 PST 2012


On 11/26/12 16:42, Henrik /KaarPoSoft wrote:
> Tanu, again: Thanks for your help.
>
> I tried the patch and various other changes to log.c, but getting no 
> useful logging, and sometimes segfaults instead (as you also noted).
>
> My mind kept coming back to this:
>
> ldd /bin/gnome-shell | grep pulse
>     libpulse-mainloop-glib.so.0 => //lib/libpulse-mainloop-glib.so.0 
> (0xb5771000)
>     libpulse.so.0 => //lib/libpulse.so.0 (0xb5723000)
>     libpulsecommon-2.1.so => //lib/pulseaudio/libpulsecommon-2.1.so 
> (0xb56b7000)
>     libpulsecommon-2.99.so => //lib/pulseaudio/libpulsecommon-2.99.so 
> (0xb4da6000)
>
> So, I deleted every file installed by the original pulseaudio 2.1, 
> recompiled+installed pulseaudio 2.99.2, and recompiled+installed 
> gnome-shell.
>
> Now I have:
>
> ldd /bin/gnome-shell | grep pulse
>     libpulse-mainloop-glib.so.0 => //lib/libpulse-mainloop-glib.so.0 
> (0xb56e3000)
>     libpulse.so.0 => //lib/libpulse.so.0 (0xb5695000)
>     libpulsecommon-2.99.so => //lib/pulseaudio/libpulsecommon-2.99.so 
> (0xb5620000)
>
> (A quick look at gnome-shell source did not reveal why it would link 
> to an old version; I must have a closer look at this later).
>
> And the good news:
> Now the clients (incl gnome-shell) are showing the "Received opcode" 
> in ~/.xsession-errors (presumably because they log to stderr which is 
> redirected to ~/.xsession-errors).
>
> And the even better news:
> I have tried to recreate the freeze without success.
> So, maybe 2.99.2 solved the original problem after all.
>
> However, I am still having some trouble...
>
> Syslog show as lot (about 5/sec to 10/sec) entries like:
> Nov 26 16:31:04 kx8400-5 pulseaudio[2078]: (1745.440|   0.057) 
> [pulseaudio] pdispatch.c: [0x9ee32f0] Received opcode 
> <GET_PLAYBACK_LATENCY>, tag 1088
> And ~/.xsession-errors shows the corresponding
> Received opcode <REPLY>
>
> I am not sure if this is a problem or not, but previously I did not 
> notice such pulseaudio bursts in syslog.
>
> Further thunderbird crashes every now and then (maybe not related, but 
> it never did that before),
> and sometimes the terminal does not beep as expected.
>
> For the latter case (terminal does not beep as expected) I have this 
> in syslog:
> [...]
It seems that more apps / libraries depend on libpulsecommon-2.1.so 
which has now been removed (as 2.99.2 has been installed instead).
So, I tried to identify every lib/app dependent on pulseaudio, and have 
now recompiled+reinstalled:
gnome-shell
alsa-plugins
libcanberra
gst-plugins-good
gnome-control-center
gnome-settings-daemon
gtk-vnc
empathy

Now I am *not* able to recreate this hang:
open gnome-terminal (as only app on desktop), press backspace, close 
terminal.

However, this seems to hang every time:
open gnome-terminal, press backspace twice to get two beeps.
open firefox, play html5 video on youtube, close firefox.
close gnome-terminal (which is now last app on desktop).
Now the desktop is frozen.

The last 40 lines of .xsession-errors:
----------------------------------------
[0x9e6df4f0] Received opcode <REQUEST>, tag 4294967295
[0x9e6df4f0] Received opcode <REQUEST>, tag 4294967295
[0x9e6df4f0] Received opcode <REQUEST>, tag 4294967295
[0x9e6df4f0] Received opcode <REQUEST>, tag 4294967295
[0x9e6df4f0] Received opcode <REQUEST>, tag 4294967295
[0x9e6df4f0] Received opcode <REQUEST>, tag 4294967295
[0x9e6df4f0] Received opcode <REQUEST>, tag 4294967295
[0xb2406210] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0x877c6e0] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0x9e6df4f0] Received opcode <REPLY>, tag 25
[0x9e6df4f0] Received opcode <REPLY>, tag 26
[0xa643cd8] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0x9e6df4f0] Received opcode <REPLY>, tag 27
[0x877c6e0] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0x9e6df4f0] Received opcode <REPLY>, tag 28
[0x877c6e0] Received opcode <REPLY>, tag 35
[0x877c6e0] Received opcode <REPLY>, tag 36
[0xa643cd8] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0x9e6df4f0] Received opcode <REPLY>, tag 29
[0xa643cd8] Received opcode <REPLY>, tag 26
[0xa643cd8] Received opcode <REPLY>, tag 27
[0x9e6df4f0] Received opcode <REPLY>, tag 30
[0x9e6df4f0] Received opcode <REPLY>, tag 31
[0x9e6df4f0] Received opcode <REPLY>, tag 32
[0x9e6df4f0] Received opcode <REPLY>, tag 33
[0x9e6df4f0] Received opcode <REPLY>, tag 34
[0x9e6df4f0] Received opcode <REPLY>, tag 35
[0x9e6df4f0] Received opcode <REPLY>, tag 36
[0x9e6df4f0] Received opcode <REPLY>, tag 37
[0x9e6df4f0] Received opcode <REPLY>, tag 38
[0xb2406210] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0x877c6e0] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0x877c6e0] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0xa643cd8] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0xa643cd8] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0xb2406210] Received opcode <ERROR>, tag 9
[0x877c6e0] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0x877c6e0] Received opcode <SUBSCRIBE_EVENT>, tag 4294967295
[0x877c6e0] Received opcode <REPLY>, tag 37
[0x877c6e0] Received opcode <REPLY>, tag 38
----------------------------------------

The last 20 lines of syslog matching grep 'Received opcode'
----------------------------------------
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.665|   0.001) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 27
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.675|   0.009) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x8666228] 
Received opcode <GET_SINK_INPUT_INFO>, tag 35
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.676|   0.001) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x86812c0] 
Received opcode <GET_SINK_INPUT_INFO>, tag 26
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.677|   0.001) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 28
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.678|   0.001) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x8666228] 
Received opcode <GET_SINK_INFO>, tag 36
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.694|   0.015) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 29
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.705|   0.011) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x86812c0] 
Received opcode <GET_SINK_INFO>, tag 27
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.734|   0.029) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 30
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.815|   0.081) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 31
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.976|   0.160) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 32
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.297|   0.320) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 33
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.325|   0.028) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 34
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.327|   0.001) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <CORK_PLAYBACK_STREAM>, tag 35
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.327|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 36
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.328|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 37
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.328|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <FLUSH_PLAYBACK_STREAM>, tag 38
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.328|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 39
Nov 28 02:05:56 kx8400-5 pulseaudio[2064]: (  54.319|   0.052) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x86828d8] 
Received opcode <PLAY_SAMPLE>, tag 9
Nov 28 02:06:00 kx8400-5 pulseaudio[2064]: (  58.343|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x8666228] 
Received opcode <GET_SINK_INFO>, tag 37
Nov 28 02:06:00 kx8400-5 pulseaudio[2064]: (  58.343|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x8666228] 
Received opcode <GET_SOURCE_INFO>, tag 38

----------------------------------------

So it would seem that tag 39 is received by pulseaudio daemon, but the 
response not received by the client (presumably gnome-shell)

(note that there seems to be two clients both now using the 30-40 range 
of tags)

Last lines of syslog:
----------------------------------------
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.705|   0.011) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x86812c0] 
Received opcode <GET_SINK_INFO>, tag 27
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.734|   0.029) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 30
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.815|   0.081) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 31
Nov 28 02:05:54 kx8400-5 pulseaudio[2064]: (  52.976|   0.160) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 32
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.297|   0.320) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 33
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.325|   0.028) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 34
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.327|   0.001) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <CORK_PLAYBACK_STREAM>, tag 35
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.327|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 36
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.328|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 37
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.328|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <FLUSH_PLAYBACK_STREAM>, tag 38
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.328|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x868dcc8] 
Received opcode <GET_PLAYBACK_LATENCY>, tag 39
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.329|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:963 update_sw_params()] hwbuf_unused=0
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.329|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:975 update_sw_params()] setting 
avail_min=15502
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.329|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:1315 sink_set_volume_cb()] 
Requested volume: 0:  71% 1:  71%
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.329|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:1316 
sink_set_volume_cb()]            in dB: 0: -9.00 dB 1: -9.00 dB
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.329|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:1317 sink_set_volume_cb()] Got 
hardware volume: 0:  71% 1:  71%
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.329|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:1318 
sink_set_volume_cb()]               in dB: 0: -9.00 dB 1: -9.00 dB
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.329|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:1321 sink_set_volume_cb()] 
Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes)
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.333|   0.003) 
[alsa-sink][modules/alsa/alsa-sink.c:1322 
sink_set_volume_cb()]                      in dB: 0: 0.00 dB 1: 0.00 dB
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.333|   0.000) 
[alsa-sink][pulsecore/sink.c:3539 pa_sink_volume_change_push()] Volume 
not changing
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.333|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:1619 process_rewind()] Requested to 
rewind 65536 bytes.
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.333|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:1642 process_rewind()] Limited to 
7236 bytes.
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.334|   0.001) 
[alsa-sink][modules/alsa/alsa-sink.c:1645 process_rewind()] before: 1809
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.336|   0.001) 
[alsa-sink][modules/alsa/alsa-sink.c:1653 process_rewind()] after: 1744
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.337|   0.001) 
[alsa-sink][modules/alsa/alsa-sink.c:1661 process_rewind()] Rewound 6976 
bytes.
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.339|   0.001) 
[alsa-sink][pulsecore/sink.c:942 pa_sink_process_rewind()] Processing 
rewind...
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.340|   0.001) 
[alsa-sink][pulsecore/sink.c:3656 pa_sink_volume_change_rewind()] 
latency = 0
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.361|   0.021) 
[alsa-sink][pulsecore/source.c:874 pa_source_process_rewind()] 
Processing rewind...
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.333|   0.000) 
[pulseaudio][modules/module-suspend-on-idle.c:120 restart()] Sink 
alsa_output.pci-0000_00_1e.2.analog-stereo becomes idle, timeout in 5 
seconds.
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.363|   0.001) 
[pulseaudio][pulsecore/core.c:256 pa_core_maybe_vacuum()] Hmm, no 
streams around, trying to vacuum.
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.385|   0.021) 
[pulseaudio][pulsecore/client.c:102 pa_client_free()] Freed 10 "ALSA 
plug-in [xulrunner]"
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.386|   0.001) 
[pulseaudio][pulsecore/protocol-native.c:4864 pstream_die_callback()] 
Connection died.
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.389|   0.003) 
[pulseaudio][pulsecore/protocol-dbus.c:844 
pa_dbus_protocol_remove_interface()] Interface 
org.PulseAudio.Core1.Stream removed from object 
/org/pulseaudio/core1/playback_stream2
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.391|   0.001) 
[pulseaudio][pulsecore/sink-input.c:675 sink_input_free()] Freeing input 
2 "ALSA Playback"
Nov 28 02:05:55 kx8400-5 pulseaudio[2064]: (  53.392|   0.001) 
[pulseaudio][pulsecore/protocol-dbus.c:844 
pa_dbus_protocol_remove_interface()] Interface 
org.PulseAudio.Core1.Client removed from object 
/org/pulseaudio/core1/client10
Nov 28 02:05:56 kx8400-5 pulseaudio[2064]: (  54.267|   0.874) 
[alsa-sink][modules/alsa/alsa-sink.c:1831 thread_func()] Wakeup from ALSA!
Nov 28 02:05:56 kx8400-5 pulseaudio[2064]: (  54.267|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:468 check_left_to_play()] Underrun!
Nov 28 02:05:56 kx8400-5 pulseaudio[2064]: (  54.267|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:332 increase_watermark()] 
Increasing wakeup watermark to 30.00 ms
Nov 28 02:05:56 kx8400-5 pulseaudio[2064]: (  54.319|   0.052) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x86828d8] 
Received opcode <PLAY_SAMPLE>, tag 9
Nov 28 02:05:57 kx8400-5 pulseaudio[2064]: (  55.753|   1.433) 
[alsa-sink][modules/alsa/alsa-sink.c:1831 thread_func()] Wakeup from ALSA!
Nov 28 02:05:57 kx8400-5 pulseaudio[2064]: (  55.753|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:468 check_left_to_play()] Underrun!
Nov 28 02:05:57 kx8400-5 pulseaudio[2064]: (  55.753|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:332 increase_watermark()] 
Increasing wakeup watermark to 40.00 ms
Nov 28 02:05:58 kx8400-5 pulseaudio[2064]: (  56.496|   0.742) 
[alsa-sink][modules/alsa/alsa-sink.c:1831 thread_func()] Wakeup from ALSA!
Nov 28 02:05:58 kx8400-5 pulseaudio[2064]: (  56.496|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:468 check_left_to_play()] Underrun!
Nov 28 02:05:58 kx8400-5 pulseaudio[2064]: (  56.496|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:332 increase_watermark()] 
Increasing wakeup watermark to 50.00 ms
Nov 28 02:05:59 kx8400-5 pulseaudio[2064]: (  57.611|   1.114) 
[alsa-sink][modules/alsa/alsa-sink.c:1831 thread_func()] Wakeup from ALSA!
Nov 28 02:05:59 kx8400-5 pulseaudio[2064]: (  57.611|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:468 check_left_to_play()] Underrun!
Nov 28 02:05:59 kx8400-5 pulseaudio[2064]: (  57.611|   0.000) 
[alsa-sink][modules/alsa/alsa-sink.c:332 increase_watermark()] 
Increasing wakeup watermark to 60.00 ms
Nov 28 02:06:00 kx8400-5 pulseaudio[2064]: (  58.337|   0.726) 
[pulseaudio][modules/module-suspend-on-idle.c:91 timeout_cb()] Sink 
alsa_output.pci-0000_00_1e.2.analog-stereo idle for too long, suspending ...
Nov 28 02:06:00 kx8400-5 pulseaudio[2064]: (  58.337|   0.000) 
[pulseaudio][pulsecore/sink.c:854 pa_sink_suspend()] Suspend cause of 
sink alsa_output.pci-0000_00_1e.2.analog-stereo is 0x0004, suspending
Nov 28 02:06:00 kx8400-5 pulseaudio[2064]: (  58.341|   0.004) 
[alsa-sink][modules/alsa/alsa-sink.c:926 suspend()] Device suspended...
Nov 28 02:06:00 kx8400-5 pulseaudio[2064]: (  58.341|   0.000) 
[pulseaudio][pulsecore/core.c:256 pa_core_maybe_vacuum()] Hmm, no 
streams around, trying to vacuum.
Nov 28 02:06:00 kx8400-5 pulseaudio[2064]: (  58.342|   0.000) 
[pulseaudio][modules/reserve-wrap.c:243 change_cb()] Device lock status 
of reserve-monitor-wrapper at Audio0 changed: not busy
Nov 28 02:06:00 kx8400-5 pulseaudio[2064]: (  58.342|   0.000) 
[pulseaudio][modules/module-udev-detect.c:287 verify_access()] 
/dev/snd/controlC0 is accessible: yes
Nov 28 02:06:00 kx8400-5 pulseaudio[2064]: (  58.343|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x8666228] 
Received opcode <GET_SINK_INFO>, tag 37
Nov 28 02:06:00 kx8400-5 pulseaudio[2064]: (  58.343|   0.000) 
[pulseaudio][pulsecore/pdispatch.c:318 pa_pdispatch_run()] [0x8666228] 
Received opcode <GET_SOURCE_INFO>, tag 38
Nov 28 02:11:08 kx8400-5 pulseaudio[2064]: ( 366.870| 308.526) 
[pulseaudio][modules/module-udev-detect.c:287 verify_access()] 
/dev/snd/controlC0 is accessible: yes
----------------------------------------

As I am quite new to pulseaudio, I have no clue how to interpret the logs.

Any help would be most appreciated.

/Henrik



More information about the pulseaudio-discuss mailing list