autospawn not working

[linuxrulez.giedrius]

PulseAudio clients are not spawning pulseaudio
Tested /etc/pulse/client.conf with:
autospawn=yes and autospawn=1
Both ways, daemon_binary was set ant unset, same with SHM usage.
Every time I get this on strace -Ffv paplay /opt/kde3/share/source/KDE_Startup.wav:

open("/home/giedrius/.pulse/client.conf", O_RDONLY) = -1 ENOENT (No such file or directory)
open("/etc/pulse/client.conf", O_RDONLY) = 10
fstat(10, {st_dev=makedev(104, 1), st_ino=346498, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0, st_blksize=4096, st_blocks=8, st_size=1475, st_atime=2008/01/31-09:02:24, st_mtime=2008/01/31-09:00:40, st_ctime=2008/01/31-09:00:40}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b3d69b7a000
read(10, "# $Id: client.conf.in 1285 2006-"..., 4096) = 1475
read(10, "", 4096)                      = 0
open("/home/giedrius/.pulse-cookie", O_RDWR|O_CREAT|O_NOCTTY, 0600) = 11
fcntl(11, F_SETLKW, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}) = 0
read(11, "\307\300\2\302\367\212\224d\316\20)\256c\n;\357\221#,\30NM\216\376\211\17\247\357\0Y\22\344"..., 256) = 256
fcntl(11, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0
close(11)                               = 0
close(10)                               = 0
munmap(0x2b3d69b7a000, 4096)            = 0
socket(PF_FILE, SOCK_STREAM, 0)         = 10
connect(10, {sa_family=AF_FILE, path="/tmp/.X11-unix/X13"}, 110) = 0
getpeername(10, {sa_family=AF_FILE, path="/tmp/.X11-unix/X13"}, [8371910236600008725]) = 0
uname({sysname="Linux", nodename="terminal", release="2.6.22.13-0.3-20080118", version="#3 SMP PREEMPT Fri Jan 18 16:23:26 EET 2008", machine="x86_64"}) = 0
access("/home/giedrius/.Xauthority", R_OK) = 0
open("/home/giedrius/.Xauthority", O_RDONLY) = 11
fstat(11, {st_dev=makedev(104, 3), st_ino=5032160, st_mode=S_IFREG|0600, st_nlink=1, st_uid=1004, st_gid=100, st_blksize=4096, st_blocks=8, st_size=121, st_atime=2008/01/31-09:02:24, st_mtime=2008/01/29-18:47:35, st_ctime=2008/01/29-18:47:35}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b3d69b7a000
read(11, "\1\0\0\10terminal\0\00213\0\22MIT-MAGIC-COOK"..., 4096) = 121
close(11)                               = 0
munmap(0x2b3d69b7a000, 4096)            = 0
fcntl(10, F_GETFL)                      = 0x2 (flags O_RDWR)
fcntl(10, F_SETFL, O_RDWR|O_NONBLOCK)   = 0
fcntl(10, F_SETFD, FD_CLOEXEC)          = 0
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"l\0\v\0\0\0\22\0\20\0", 10}, {"\0\0", 2}, {"MIT-MAGIC-COOKIE-1", 18}, {"\0\0", 2}, {"\243\302-\215\223\356O1\7\326\242\361\315\321\313?", 16}, {"", 0}], 6) = 48
read(10, "\1\0\v\0\0\0005\0", 8)        = 8
read(10, "\320\303\237\3\0\0\200\3\377\377\37\0\0\1\0\0\24\0\377\377\1\2\0\0  \10\353\1\0\0\0"..., 212) = 212
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"7\0\5\0\0\0\200\3)\0\0\0\10\0\0\0\377\377\377\0b\0\5\0\f\0\0\0BIG-"..., 40}], 1) = 40
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\0\2\0\0\0\0\0\1\205\0\0\0\0\0\0\0\0\0\0\0\0\0\0(\200\341\10\210\303\6\t", 4096) = 32
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"\205\0\1\0", 4}], 1)      = 4
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\0\3\0\0\0\0\0\377\377?\0\0\0\0\0\1\0\0\0\0\0\0\0(\200\341\10\210\303\6\t", 4096) = 32
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"\24\0\6\0)\0\0\0\27\0\0\0\37\0\0\0\0\0\0\0\0\341\365\5", 24}], 1) = 24
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\10\4\0g\20\0\0\37\0\0\0\0\0\0\0\232A\0\0\0\0\0\0(\0\0\0\0\0\0\0"..., 4096) = 4096
read(10, "hlightThickness:\t2\n*Toggle.backg"..., 12732) = 12732
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
brk(0x648000)                           = 0x648000
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"b\0\5\0\t\0\0\0XKEYBOARD\0\0\0", 20}], 1) = 20
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\0\5\0\0\0\0\0\1\213W\224\0\0\0\0\1\0\0\0\0\0\0\0(\200\341\10\210\303\6\t", 4096) = 32
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"\213\0\2\0\1\0\0\0", 8}], 1) = 8
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\1\6\0\0\0\0\0\1\0\0\0\0\0\0\0\1\0\0\0\0\0\0\0(\200\341\10\210\303\6\t", 4096) = 32
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"\20\0\5\0\f\0\0\0PULSE_SERVER", 20}], 1) = 20
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\0\7\0\0\0\0\0\24\1\0\0\0\0\0\0\1\0\0\0\0\0\0\0(\200\341\10\210\303\6\t", 4096) = 32
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"\24\0\6\0)\0\0\0\24\1\0\0\37\0\0\0\0\0\0\0\0\1\0\0", 24}], 1) = 24
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\10\10\0\v\0\0\0\37\0\0\0\0\0\0\0*\0\0\0\0\0\0\0(\0\0\0\0\0\0\0"..., 4096) = 76
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"\20\0\5\0\n\0\0\0PULSE_SINK\0\0", 20}], 1) = 20
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\0\t\0\0\0\0\0\352\1\0\0\0\0\0\0\1\0\0\0\0\0\0\0(\200\341\10\210\303\6\t", 4096) = 32
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"\24\0\6\0)\0\0\0\352\1\0\0\37\0\0\0\0\0\0\0\0\1\0\0", 24}], 1) = 24
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\0\n\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0(\0\0\0\0\0\0\0", 4096) = 32
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"\20\0\5\0\f\0\0\0PULSE_SOURCE", 20}], 1) = 20
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\0\v\0\0\0\0\0\354\1\0\0\0\0\0\0\1\0\0\0\0\0\0\0(\200\341\10\210\303\6\t", 4096) = 32
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"\24\0\6\0)\0\0\0\354\1\0\0\37\0\0\0\0\0\0\0\0\1\0\0", 24}], 1) = 24
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\0\f\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0(\0\0\0\0\0\0\0", 4096) = 32
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"\20\0\5\0\f\0\0\0PULSE_COOKIE", 20}], 1) = 20
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\0\r\0\0\0\0\0\26\1\0\0\0\0\0\0\1\0\0\0\0\0\0\0(\200\341\10\210\303\6\t", 4096) = 32
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"\24\0\6\0)\0\0\0\26\1\0\0\37\0\0\0\0\0\0\0\0\1\0\0", 24}], 1) = 24
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\0\16\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0(\0\0\0\0\0\0\0", 4096) = 32
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
select(11, [10], [10], NULL, NULL)      = 1 (out [10])
writev(10, [{"<\0\2\0\0\0\200\3+\0\1\0", 12}], 1) = 12
select(11, [10], [], NULL, NULL)        = 1 (in [10])
read(10, "\1\1\20\0\0\0\0\0\v\0@\3\0\0\0\0\0\0\0\0\0\0\0\0(\200\341\10\210\303\6\t", 4096) = 32
read(10, 0x618b44, 4096)                = -1 EAGAIN (Resource temporarily unavailable)
close(10)                               = 0
brk(0x640000)                           = 0x640000
futex(0x7fff40fb87b4, FUTEX_UNLOCK_PI, 0) = -1 EPERM (Operation not permitted)
open("/dev/shm/", O_RDONLY|O_NONBLOCK|O_DIRECTORY) = 10
fstat(10, {st_dev=makedev(0, 19), st_ino=5823, st_mode=S_IFDIR|S_ISVTX|0777, st_nlink=7, st_uid=0, st_gid=0, st_blksize=4096, st_blocks=0, st_size=140, st_atime=2008/01/31-09:02:24, st_mtime=2008/01/31-08:31:32, st_ctime=2008/01/31-08:31:32}) = 0
fcntl(10, F_SETFD, FD_CLOEXEC)          = 0
getdents64(10, {{d_ino=5823, d_off=1, d_type=DT_DIR, d_reclen=24, d_name="."} {d_ino=5823, d_off=2, d_type=DT_DIR, d_reclen=24, d_name=".."} {d_ino=2407806, d_off=3, d_type=DT_DIR, d_reclen=32, d_name="jack-1001"} {d_ino=2029460, d_off=4, d_type=DT_DIR, d_reclen=32, d_name="jack-1005"} {d_ino=1930134, d_off=5, d_type=DT_DIR, d_reclen=32, d_name="jack-1004"} {d_ino=1922284, d_off=6, d_type=DT_DIR, d_reclen=32, d_name="jack-0"} {d_ino=6333, d_off=7, d_type=DT_DIR, d_reclen=32, d_name="sysconfig"}}, 4096) = 208
getdents64(10, {}, 4096)                = 0
close(10)                               = 0
mmap(NULL, 2097152, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b3d6bf35000
uname({sysname="Linux", nodename="terminal", release="2.6.22.13-0.3-20080118", version="#3 SMP PREEMPT Fri Jan 18 16:23:26 EET 2008", machine="x86_64"}) = 0
socket(PF_FILE, SOCK_STREAM, 0)         = 10
fcntl(10, F_GETFD)                      = 0
fcntl(10, F_SETFD, FD_CLOEXEC)          = 0
setsockopt(10, SOL_SOCKET, SO_PRIORITY, [6], 4) = 0
fcntl(10, F_GETFL)                      = 0x2 (flags O_RDWR)
fcntl(10, F_SETFL, O_RDWR|O_NONBLOCK)   = 0
connect(10, {sa_family=AF_FILE, path="/tmp/pulse-giedrius/native"}, 110) = -1 ENOENT (No such file or directory)

client.conf:

 $Id: client.conf.in 1285 2006-08-19 01:18:57Z lennart $
#
# This file is part of PulseAudio.
#
# PulseAudio is free software; you can redistribute it and/or modify
# it under the terms of the GNU Lesser General Public License as published by
# the Free Software Foundation; either version 2 of the License, or
# (at your option) any later version.
#
# PulseAudio is distributed in the hope that it will be useful, but
# WITHOUT ANY WARRANTY; without even the implied warranty of
# MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the GNU
# General Public License for more details.
#
# You should have received a copy of the GNU Lesser General Public License
# along with PulseAudio; if not, write to the Free Software
# Foundation, Inc., 59 Temple Place, Suite 330, Boston, MA 02111-1307
# USA.

## Configuration file for pulseaudio clients. Default values are
## commented out.  Use either ; or # for commenting

## Path to the pulseaudio daemon to run when autospawning.

 daemon-binary = /usr/bin/pulseaudio

; daemon-binary = /usr/local/bin/pulse_wrapper

## Extra arguments to pass to the pulseaudio daemon
extra-arguments = --log-target=syslog --exit-idle-time=5

## The default sink to connect to
; default-sink =

## The default source to connect to
; default-source =

## The default sever to connect to
; default-server =

## Autospawn daemons?
autospawn = 1

### Cookie file
; cookie-file =

### Disable shared memory data transfer
disable-shm = yes

On some "magical" condition it gets to fork new process for pulseaudio daemon, but I can't manage to identify when that happens.

pulseaudio 0.9.8 on x86_64

please help :(
I can't afford running pulseaudio always - after some time it dumps it's SOURCE and then SINK (JACK) as stops, leaving pulse clients on dead-wait or crash.

[linuxrulez.giedrius]

More info: strace -Ffv pactl stat

[pid 17347] open("/usr/lib64/pulse-0.9/modules/module-native-protocol-fd.so", O_RDONLY) = 14
[pid 17347] read(14, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240\n\0\0\0\0\0\0"..., 832) = 832
[pid 17347] fstat(14, {st_dev=makedev(104, 1), st_ino=473606, st_mode=S_IFREG|0755, st_nlink=1, st_uid=0, st_gid=0, st_blksize=4096, st_blocks=32, st_size=14595, st_atime=2008/01/31-10:25:21, st_mtime=2008/01/30-16:56:33, st_ctime=2008/01/31-09:31:48}) = 0
[pid 17347] mmap(NULL, 2105448, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 14, 0) = 0x2aaab2bf6000
[pid 17347] fadvise64(14, 0, 2105448, POSIX_FADV_WILLNEED) = 0
[pid 17347] mprotect(0x2aaab2bf7000, 2097152, PROT_NONE) = 0
[pid 17347] mmap(0x2aaab2df7000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 14, 0x1000) = 0x2aaab2df7000
[pid 17347] close(14)                   = 0
[pid 17347] mprotect(0x2aaab2df7000, 4096, PROT_READ) = 0
[pid 17347] fcntl(11, F_GETFL)          = 0x2 (flags O_RDWR)
[pid 17347] fcntl(11, F_SETFL, O_RDWR|O_NONBLOCK) = 0
[pid 17347] write(2, "pulseaudio: pulsecore/protocol-n"..., 83) = 83
[pid 17347] rt_sigprocmask(SIG_UNBLOCK, [ABRT], NULL, 8) = 0
[pid 17347] tgkill(17347, 17347, SIGABRT) = 0
[pid 17347] --- SIGABRT (Aborted) @ 0 (0) ---
Process 17347 detached
[pid 17349] <... ppoll resumed> [{fd=20, events=POLLIN}, {fd=16, events=POLLIN}], 2, {...}, [], 8) = ? ERESTARTNOHAND (To be restarted)
[pid 17350] <... poll resumed> [{fd=22, events=POLLIN|POLLERR|POLLHUP|POLLNVAL}, {fd=23, events=POLLIN|POLLERR|POLLHUP|POLLNVAL}], 2, 1000) = -1 EINTR (Interrupted system call)
[pid 17352] <... ppoll resumed> [{fd=30, events=POLLIN}, {fd=26, events=POLLIN}], 2, {...}, [], 8) = ? ERESTARTNOHAND (To be restarted)
[pid 17353] <... poll resumed> [{fd=32, events=POLLIN|POLLERR|POLLHUP|POLLNVAL}, {fd=33, events=POLLIN|POLLERR|POLLHUP|POLLNVAL}], 2, 1000) = -1 EINTR (Interrupted system call)
[pid 17349] +++ killed by SIGABRT +++
[pid 17350] +++ killed by SIGABRT +++
[pid 17353] +++ killed by SIGABRT +++
[pid 17346] <... read resumed> "", 4)   = 0
[pid 17346] ioctl(2, SNDCTL_TMR_TIMEBASE or TCGETS, {c_iflags=0x4100, c_oflags=0x5, c_cflags=0xbf, c_lflags=0x8a3b, c_line=0, c_cc="\x03\x1c\x7f\x15\x04\x00\x01\x00\x11\x13\x1a\x00\x12\x0f\x17\x16\x00\x00\x00"}) = 0
[pid 17346] write(2, "E: main.c: \33[1;31mread() failed:"..., 55E: main.c: read() failed: Permission denied
 <unfinished ...>
[pid 17352] +++ killed by SIGABRT +++
[pid 17346] <... write resumed> )       = 55
[pid 17346] --- SIGCHLD (Child exited) @ 0 (0) ---
[pid 17346] ioctl(2, SNDCTL_TMR_TIMEBASE or TCGETS, {c_iflags=0x4100, c_oflags=0x5, c_cflags=0xbf, c_lflags=0x8a3b, c_line=0, c_cc="\x03\x1c\x7f\x15\x04\x00\x01\x00\x11\x13\x1a\x00\x12\x0f\x17\x16\x00\x00\x00"}) = 0
[pid 17346] write(2, "E: main.c: \33[1;31mdaemon startup"..., 45E: main.c: daemon startup failed.
) = 45
[pid 17346] close(3)                    = 0
[pid 17346] exit_group(1)               = ?
Process 17345 resumed
Process 17346 detached
[pid 17345] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 1}], 0, NULL) = 17346
[pid 17345] --- SIGCHLD (Child exited) @ 0 (0) ---
[pid 17345] write(2, "Connection failure: Connection r"..., 39Connection failure: Connection refused
) = 39
[pid 17345] close(10)                   = 0
[pid 17345] close(11)                   = 0
[pid 17345] unlink("/tmp/pulse-giedrius/autospawn.lock") = 0
[pid 17345] fcntl(9, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0
[pid 17345] close(9)                    = 0
[pid 17345] munmap(0x2aad89175000, 2097152) = 0
[pid 17345] rt_sigaction(SIGINT, {SIG_DFL}, NULL, 8) = 0
[pid 17345] close(6)                    = 0
[pid 17345] close(8)                    = 0
[pid 17345] close(3)                    = 0
[pid 17345] close(4)                    = 0
[pid 17345] exit_group(1)               = ?
Process 17347 detached

This worked after I've launched pulseaudio and stopped it (CTRL-C)

pulseaudio = 0.9.8-6.1

[linuxrulez.giedrius]

Sorry for the rush-comment. This hack works (attempts to spawn daemon) only for the first time. The second and every next time I try pactl stat, gives the same results - no new process is started and no pulseaudio process exists. The only way I managed autospawn to attempt to launch daemon is to start pulseaudio manually (no extra cmdline params), SIGINT(CTRL-C) it and ONLY THEN pactl stat. This way new process IS launched, but (from strace output) gets SIGABRT'ed

Deleting pid and/or socket file(s) gives no help. SHM is clean.

[coling]

Just FYI, the fact autospawn doesn't work is kinda known. Not sure if there is another ticket for it, but it's been flaky for a few releases. I don't think it's really the recommended route for starting PA but perhaps I'm not 100% accurate here.

[tanuk]

I can't afford running pulseaudio always - after some time it dumps it's SOURCE and then SINK (JACK) as stops, leaving pulse clients on dead-wait or crash.

If I understood correctly, after some time the jack modules are unloaded, while both pulseaudio and jackd keep running. That behaviour suggests that pulse times out when jackd asks it to process data. The jack modules (or actually sink only, I don't need source) are working very solidly on my machine, so I think this is just a configuration issue, or more specifically: you don't run jackd and/or pulseaudio in realtime mode. Running pulseaudio in realtime mode is pretty much required when using the jack modules (it shouldn't be that way, but since using realtime is so common when using jack, fixing it hasn't been a high priority).

[linuxrulez.giedrius]

Replying to coling:

Just FYI, the fact autospawn doesn't work is kinda known. Not sure if there is another ticket for it, but it's been flaky for a few releases. I don't think it's really the recommended route for starting PA but perhaps I'm not 100% accurate here.

I know it's not the best way to do it, but everything else fails: loading pulseaudio directly on SunRAY OSS results in unloaded module (and NO, patch to utadem.c does NOT help) and pulseaudio clients crashing.

[linuxrulez.giedrius]

Replying to tanuk:

I can't afford running pulseaudio always - after some time it dumps it's SOURCE and then SINK (JACK) as stops, leaving pulse clients on dead-wait or crash.

If I understood correctly, after some time the jack modules are unloaded, while both pulseaudio and jackd keep running. That behaviour suggests that pulse times out when jackd asks it to process data. The jack modules (or actually sink only, I don't need source) are working very solidly on my machine, so I think this is just a configuration issue, or more specifically: you don't run jackd and/or pulseaudio in realtime mode. Running pulseaudio in realtime mode is pretty much required when using the jack modules (it shouldn't be that way, but since using realtime is so common when using jack, fixing it hasn't been a high priority).

Hmmm didn't knew that... I'll try loading both in realtime. For the moment, SOURCE is killed first. Leaving pulse subprocess zombyfied.

[lennart]

Autospawning has been reworked quite a bit in current git, closing this now because everything seems to work fine now. Feel free to reopen if the problem persists with current git versions.