Bug 107206

Summary:	Pulseaudio refuses connections from applications
Product:	PulseAudio	Reporter:	Lukáš Růžička <lruzicka>
Component:	daemon	Assignee:	pulseaudio-bugs
Status:	RESOLVED MOVED	QA Contact:	pulseaudio-bugs
Severity:	normal
Priority:	medium	CC:	lennart
Version:	unspecified
Hardware:	x86-64 (AMD64)
OS:	Linux (All)
Whiteboard:
i915 platform:		i915 features:

Description Lukáš Růžička 2018-07-12 13:07:18 UTC

Description of problem:
===========================

On Fedora 28 (updates-testing on) in Fluxbox, whenever the computer is freshly started, pulseaudio refuses to connect applications which results in no sound available. The daemon is running.

The journal reports the following:
====
Jul 09 12:52:10 platypus rtkit-daemon[1101]: Successfully made thread 2069 of process 2069 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Jul 09 12:52:10 platypus pulseaudio[2069]: bind(): Address already in use
Jul 09 12:52:10 platypus pulseaudio[2069]: Failed to load module "module-native-protocol-unix" (argument: ""): initialization failed.
Jul 09 12:52:10 platypus pulseaudio[2069]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files
Jul 09 12:52:10 platypus rtkit-daemon[1101]: Successfully made thread 2074 of process 2074 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Jul 09 12:52:10 platypus pulseaudio[2074]: E: [pulseaudio] pid.c: Daemon already running.
Jul 09 12:52:10 platypus pulseaudio[2074]: E: [pulseaudio] main.c: pa_pid_file_create() failed.
Jul 09 12:52:10 platypus systemd[1924]: pulseaudio.service: Main process exited, code=exited, status=1/FAILURE
Jul 09 12:52:10 platypus systemd[1924]: pulseaudio.service: Failed with result 'exit-code'.
Jul 09 12:52:10 platypus systemd[1924]: pulseaudio.service: Service hold-off time over, scheduling restart.
Jul 09 12:52:10 platypus systemd[1924]: pulseaudio.service: Scheduled restart job, restart counter is at 1.
Jul 09 12:52:10 platypus rtkit-daemon[1101]: Successfully made thread 2087 of process 2087 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Jul 09 12:52:10 platypus pulseaudio[2087]: E: [pulseaudio] pid.c: Daemon already running.
Jul 09 12:52:10 platypus pulseaudio[2087]: E: [pulseaudio] main.c: pa_pid_file_create() failed.
Jul 09 12:52:10 platypus systemd[1924]: pulseaudio.service: Main process exited, code=exited, status=1/FAILURE
Jul 09 12:52:10 platypus systemd[1924]: pulseaudio.service: Failed with result 'exit-code'.
Jul 09 12:52:10 platypus systemd[1924]: pulseaudio.service: Service hold-off time over, scheduling restart.
Jul 09 12:52:10 platypus systemd[1924]: pulseaudio.service: Scheduled restart job, restart counter is at 2.
Jul 09 12:52:11 platypus rtkit-daemon[1101]: Successfully made thread 2090 of process 2090 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Jul 09 12:52:11 platypus pulseaudio[2090]: E: [pulseaudio] pid.c: Daemon already running.
Jul 09 12:52:11 platypus pulseaudio[2090]: E: [pulseaudio] main.c: pa_pid_file_create() failed.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Main process exited, code=exited, status=1/FAILURE
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Failed with result 'exit-code'.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Service hold-off time over, scheduling restart.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Scheduled restart job, restart counter is at 3.
Jul 09 12:52:11 platypus rtkit-daemon[1101]: Successfully made thread 2126 of process 2126 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Jul 09 12:52:11 platypus pulseaudio[2126]: E: [pulseaudio] pid.c: Daemon already running.
Jul 09 12:52:11 platypus pulseaudio[2126]: E: [pulseaudio] main.c: pa_pid_file_create() failed.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Main process exited, code=exited, status=1/FAILURE
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Failed with result 'exit-code'.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Service hold-off time over, scheduling restart.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Scheduled restart job, restart counter is at 4.
Jul 09 12:52:11 platypus rtkit-daemon[1101]: Successfully made thread 2166 of process 2166 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Jul 09 12:52:11 platypus pulseaudio[2166]: E: [pulseaudio] pid.c: Daemon already running.
Jul 09 12:52:11 platypus pulseaudio[2166]: E: [pulseaudio] main.c: pa_pid_file_create() failed.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Main process exited, code=exited, status=1/FAILURE
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Failed with result 'exit-code'.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Service hold-off time over, scheduling restart.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Scheduled restart job, restart counter is at 5.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Start request repeated too quickly.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.service: Failed with result 'exit-code'.
Jul 09 12:52:11 platypus systemd[1924]: pulseaudio.socket: Failed with result 'service-start-limit-hit'.
====

When I start firefox in console mode and try to play a video with sound:
=====
[Child 2768, MediaPlayback #2] WARNING: 7f294cac47c0 OpenCubeb() failed to init cubeb: file /builddir/build/BUILD/firefox-61.0/dom/media/AudioStream.cpp, line 390
[Child 2768, MediaPlayback #2] WARNING: Decoder=7f294c31a880 [OnMediaSinkAudioError]: file /builddir/build/BUILD/firefox-61.0/dom/media/MediaDecoderStateMachine.cpp, line 3716

###!!! [Parent][DispatchAsyncMessage] Error: PClientSourceOp::Msg___delete__ Route error: message sent to unknown actor ID
=====

Mplayer reports this:
=====
AO: [pulse] Init failed: Connection refused
Failed to initialize audio driver 'pulse'
[AO_ALSA] alsa-lib: pulse.c:243:(pulse_connect) PulseAudio: Unable to connect: Connection refused

[AO_ALSA] Playback open error: Connection refused
Failed to initialize audio driver 'alsa'
[AO OSS] audio_setup: Can't open audio device /dev/dsp: No such file or directory
[AO_ALSA] alsa-lib: pulse.c:243:(pulse_connect) PulseAudio: Unable to connect: Connection refused

[AO_ALSA] Playback open error: Connection refused
AO: [pulse] Init failed: Connection refused
[AO SDL] Samplerate: 44100Hz Channels: Stereo Format s16le
[AO_ALSA] alsa-lib: pulse.c:243:(pulse_connect) PulseAudio: Unable to connect: Connection refused

[AO SDL] Unable to open audio: No available audio device
DVB card number must be between 1 and 4
AO: [null] 44100Hz 2ch s16le (2 bytes per sample)
=====

alsamixer:
====
ALSA lib pulse.c:243:(pulse_connect) PulseAudio: Unable to connect: Connection refused

cannot open mixer: Connection refused
====

pavucontrol:
====
Establishing connection to PulseAudio. Please wait.
====

Version-Release number of selected component (if applicable):
===============================================================

pulseaudio-12.0-3.fc28.x86_64
on Linux platypus 4.17.4-200.fc28.x86_64 #1 SMP Tue Jul 3 14:06:39 UTC 2018 x86_64 x86_64 x86_64 GNU/Linux

How reproducible:
===========================

Always

Steps to Reproduce:
===========================
1. Reboot the computer.
2. Log into a Fluxbox session.
3. Try to play songs.

Actual results:
===========================

Pulseaudio refuses connections.

Expected results:

Pulseaudio connects applications to the device and play sounds.

Additional info:
===========================

1. In Gnome shell, pulseaudio behaves correctly.
2. Running pulseaudio -k and pulseaudio -D solves the situation and pulseaudio behaves correctly until next reboot.

Comment 1 Lukáš Růžička 2018-07-12 13:13:20 UTC

Downgrading to previous version solves this problem.

Comment 2 Lukáš Růžička 2018-07-12 13:32:18 UTC

Also, with new kernel 4.17.5 and pulseaudio 12.0.3, the problem remains the same.

Comment 3 Tanu Kaskinen 2018-07-27 09:15:27 UTC

Sorry for the slow reply...

It looks like systemd is having trouble starting pulseaudio, because something else has already started it (and yet applications have trouble using that other pulseaudio instance). What do these two commands print?

    pstree -T
    sudo netstat -lxp | grep pulse

Use the "Add an attachment" link rather than copying the text to the comment field (the pstree output can be quite long, and long pastes make the discussion more difficult to follow).

Comment 4 GitLab Migration User 2018-07-30 09:57:57 UTC

-- GitLab Migration Automatic Message --

This bug has been migrated to freedesktop.org's GitLab instance and has been closed from further activity.

You can subscribe and participate further through the new bug through this link to our GitLab instance: https://gitlab.freedesktop.org/pulseaudio/pulseaudio/issues/137.

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.