#898: Bluetooth sink isn't created when pulseaudio run as system daemon
--------------------+-------------------------------------------------------
Reporter: murz | Owner: lennart
Type: defect | Status: new
Milestone: | Component: daemon
Keywords: |
--------------------+-------------------------------------------------------
I need to use pulseaudio as system daemon, not per-user.
Version is 1:0.9.22-0ubuntu2~diwic
When I run pulseaudio as user and connect bluetooth audio, new sink
creates successfully.
But if I run pulseaudio as system daemon, bluetooth audio connects, but
sink is not created, here is output of pulseaudio -vv:
{{{
Jan 22 13:25:41 kit pulseaudio[2934]: bluetooth-util.c: dbus:
interface=org.bluez.Headset,
path=/org/bluez/2167/hci0/dev_00_22_FC_74_AA_3C, member=PropertyChanged
Jan 22 13:25:41 kit pulseaudio[2934]: bluetooth-util.c: dbus:
interface=org.bluez.Audio,
path=/org/bluez/2167/hci0/dev_00_22_FC_74_AA_3C, member=PropertyChanged
Jan 22 13:25:41 kit pulseaudio[2934]: bluetooth-util.c: dbus:
interface=org.bluez.Device,
path=/org/bluez/2167/hci0/dev_00_22_FC_74_AA_3C, member=PropertyChanged
Jan 22 13:25:41 kit bluetoothd[2172]: link_key_request
(sba=00:15:83:3F:6D:E7, dba=00:22:FC:74:AA:3C)
Jan 22 13:25:41 kit bluetoothd[2172]: link_key_request
(sba=00:15:83:3F:6D:E7, dba=00:22:FC:74:AA:3C)
Jan 22 13:25:43 kit pulseaudio[2934]: bluetooth-util.c: dbus:
interface=org.bluez.Headset,
path=/org/bluez/2167/hci0/dev_00_22_FC_74_AA_3C, member=PropertyChanged
Jan 22 13:25:44 kit pulseaudio[2934]: last message repeated 3 times
Jan 22 13:25:44 kit pulseaudio[2934]: bluetooth-util.c: dbus:
interface=org.bluez.AudioSink,
path=/org/bluez/2167/hci0/dev_00_22_FC_74_AA_3C, member=PropertyChanged
Jan 22 13:25:44 kit bluetoothd[2172]: Badly formated or unrecognized
command: AT+CSRSF=1,1,1,1,1,1
Jan 22 13:25:45 kit kernel: [ 8268.925610] input: 00:22:FC:74:AA:3C as
/devices/virtual/input/input24
Jan 22 13:25:45 kit pulseaudio[2934]: bluetooth-util.c: dbus:
interface=org.bluez.AudioSink,
path=/org/bluez/2167/hci0/dev_00_22_FC_74_AA_3C, member=PropertyChanged
Jan 22 13:25:45 kit pulseaudio[2934]: bluetooth-util.c: dbus:
interface=org.bluez.AudioSink,
path=/org/bluez/2167/hci0/dev_00_22_FC_74_AA_3C, member=PropertyChanged
Jan 22 13:25:45 kit pulseaudio[2934]: bluetooth-util.c: dbus:
interface=org.bluez.Audio,
path=/org/bluez/2167/hci0/dev_00_22_FC_74_AA_3C, member=PropertyChanged
Jan 22 13:25:45 kit pulseaudio[2934]: client.c: Created 6 "Native client
(UNIX socket client)"
Jan 22 13:25:45 kit pulseaudio[2934]: protocol-native.c: Protocol version:
remote 16, local 16
Jan 22 13:25:45 kit pulseaudio[2934]: protocol-native.c: Got credentials:
uid=1000 gid=100 success=1
Jan 22 13:25:45 kit pulseaudio[2934]: protocol-native.c: SHM possible: no
Jan 22 13:25:45 kit pulseaudio[2934]: protocol-native.c: Negotiated SHM:
no
Jan 22 13:25:45 kit pulseaudio[2934]: module-stream-restore.c: Restoring
volume for sink input sink-input-by-media-role:event.
Jan 22 13:25:45 kit pulseaudio[2934]: sink.c: Suspend cause of sink
alsa_output.pci-0000_00_14.2.analog-surround-51 is 0x0000, resuming
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Trying resume...
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-util.c: Maximum hw buffer size
is 7281 ms
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-util.c: Set buffer size first
(to 96000 samples), period size second (to 48000 samples).
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: hwbuf_unused=0
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: setting avail_min=95041
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Resumed successfully...
Jan 22 13:25:45 kit pulseaudio[2934]: module-suspend-on-idle.c: Sink
alsa_output.pci-0000_00_14.2.analog-surround-51 becomes idle, timeout in 5
seconds.
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Starting playback.
Jan 22 13:25:45 kit pulseaudio[2934]: ratelimit.c: 24 events suppressed
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Cutting sleep time for
the initial iterations by half.
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Cutting sleep time for
the initial iterations by half.
Jan 22 13:25:45 kit pulseaudio[2934]: module-suspend-on-idle.c: Sink
alsa_output.pci-0000_00_14.2.analog-surround-51 becomes busy.
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: Forcing resampler
'copy', because of fixed, identical sample rates.
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: Channel matrix:
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: I00 I01
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: +------------
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: O00 | 1.000 0.000
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: O01 | 0.000 1.000
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: O02 | 1.000 0.000
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: O03 | 0.000 1.000
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: O04 | 0.500 0.500
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: O05 | 0.500 0.500
Jan 22 13:25:45 kit pulseaudio[2934]: remap.c: Using generic matrix
remapping
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: Using resampler 'copy'
Jan 22 13:25:45 kit pulseaudio[2934]: resampler.c: Using s16le as working
format.
Jan 22 13:25:45 kit pulseaudio[2934]: memblockq.c: memblockq requested:
maxlength=33554432, tlength=0, base=12, prebuf=0, minreq=1 maxrewind=0
Jan 22 13:25:45 kit pulseaudio[2934]: memblockq.c: memblockq sanitized:
maxlength=33554436, tlength=33554436, base=12, prebuf=0, minreq=12
maxrewind=0
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c: Created input 0 "Audio
Stream" on alsa_output.pci-0000_00_14.2.analog-surround-51 with sample
spec s16le 2ch 48000Hz and channel map front-left,front-right
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c: media.name =
"Audio Stream"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c: application.name =
"knotify4"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c: native-
protocol.peer = "UNIX socket client"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c: native-
protocol.version = "16"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c: media.role =
"event"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c: phonon.streamid =
"{0eb43bd1-9c05-4ff4-85ba-4c666b00cb7d}"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c:
application.process.id = "3743"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c:
application.process.user = "murz"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c:
application.process.host = "kit"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c:
application.process.binary = "knotify4"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c:
application.language = "en_US.UTF-8"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c: window.x11.display
= ":0.0"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c:
application.process.machine_id = "a552e69c4374f22965f68c834a924e39"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c:
application.process.session_id =
"a552e69c4374f22965f68c834a924e39-1295683734.22308-1259993552"
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c: module-stream-
restore.id = "sink-input-by-media-role:event"
Jan 22 13:25:45 kit pulseaudio[2934]: protocol-native.c: Requested
tlength=250.00 ms, minreq=20.00 ms
Jan 22 13:25:45 kit pulseaudio[2934]: protocol-native.c: Traditional mode
enabled, modifying sink usec only for compat with minreq.
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Cutting sleep time for
the initial iterations by half.
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Cutting sleep time for
the initial iterations by half.
Jan 22 13:25:45 kit pulseaudio[2934]: memblockq.c: memblockq requested:
maxlength=4194304, tlength=48000, base=4, prebuf=44164, minreq=3840
maxrewind=0
Jan 22 13:25:45 kit pulseaudio[2934]: memblockq.c: memblockq sanitized:
maxlength=4194304, tlength=48000, base=4, prebuf=44164, minreq=3840
maxrewind=0
Jan 22 13:25:45 kit pulseaudio[2934]: protocol-native.c: Final latency
460.00 ms = 210.00 ms + 2*20.00 ms + 210.00 ms
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Cutting sleep time for
the initial iterations by half.
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Latency set to 210.00ms
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: hwbuf_unused=1031040
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: setting avail_min=95041
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Requesting rewind due
to latency change.
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Requested to rewind
1152000 bytes.
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Limited to 1149828
bytes.
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: before: 95819
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: after: 95819
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Rewound 1149828 bytes.
Jan 22 13:25:45 kit pulseaudio[2934]: sink.c: Processing rewind...
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c: Have to rewind 1149828
bytes on render memblockq.
Jan 22 13:25:45 kit pulseaudio[2934]: source.c: Processing rewind...
Jan 22 13:25:45 kit pulseaudio[2934]: core-subscribe.c: Dropped redundant
event due to change event.
Jan 22 13:25:45 kit pulseaudio[2934]: protocol-native.c: Requesting rewind
due to end of underrun.
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Requested to rewind
1152000 bytes.
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Limited to 111672
bytes.
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: before: 9306
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: after: 9306
Jan 22 13:25:45 kit pulseaudio[2934]: alsa-sink.c: Rewound 111672 bytes.
Jan 22 13:25:45 kit pulseaudio[2934]: sink.c: Processing rewind...
Jan 22 13:25:45 kit pulseaudio[2934]: sink-input.c: Have to rewind 111672
bytes on render memblockq.
Jan 22 13:25:45 kit pulseaudio[2934]: source.c: Processing rewind...
Jan 22 13:25:47 kit pulseaudio[2934]: alsa-sink.c: hwbuf_unused=0
Jan 22 13:25:47 kit pulseaudio[2934]: alsa-sink.c: setting avail_min=95041
Jan 22 13:25:47 kit pulseaudio[2934]: alsa-sink.c: Requested to rewind
1152000 bytes.
Jan 22 13:25:47 kit pulseaudio[2934]: alsa-sink.c: Limited to 118716
bytes.
Jan 22 13:25:47 kit pulseaudio[2934]: alsa-sink.c: before: 9893
Jan 22 13:25:47 kit pulseaudio[2934]: alsa-sink.c: after: 9893
Jan 22 13:25:47 kit pulseaudio[2934]: alsa-sink.c: Rewound 118716 bytes.
Jan 22 13:25:47 kit pulseaudio[2934]: sink.c: Processing rewind...
Jan 22 13:25:47 kit pulseaudio[2934]: source.c: Processing rewind...
Jan 22 13:25:47 kit pulseaudio[2934]: module-suspend-on-idle.c: Sink
alsa_output.pci-0000_00_14.2.analog-surround-51 becomes idle, timeout in 5
seconds.
Jan 22 13:25:47 kit pulseaudio[2934]: module-suspend-on-idle.c: Sink
alsa_output.pci-0000_00_14.2.analog-surround-51 becomes idle, timeout in 5
seconds.
Jan 22 13:25:47 kit pulseaudio[2934]: core.c: Hmm, no streams around,
trying to vacuum.
Jan 22 13:25:47 kit pulseaudio[2934]: sink-input.c: Freeing input 0 "Audio
Stream"
Jan 22 13:25:52 kit pulseaudio[2934]: module-suspend-on-idle.c: Sink
alsa_output.pci-0000_00_14.2.analog-surround-51 idle for too long,
suspending ...
Jan 22 13:25:52 kit pulseaudio[2934]: sink.c: Suspend cause of sink
alsa_output.pci-0000_00_14.2.analog-surround-51 is 0x0004, suspending
Jan 22 13:25:52 kit pulseaudio[2934]: alsa-sink.c: Device suspended...
Jan 22 13:25:52 kit pulseaudio[2934]: module-udev-detect.c:
/dev/snd/controlC0 is accessible: yes
}}}
Maybe I need to give some specific permissions? Or where maybe the
problem?
--
Ticket URL: <http://pulseaudio.org/ticket/898>
PulseAudio <http://pulseaudio.org/>
The PulseAudio Sound Server
_______________________________________________
pulseaudio-tickets mailing list
pulseaudio-tickets@mail.0pointer.de
https://tango.0pointer.de/mailman/listinfo/pulseaudio-tickets
