[pulseaudio-tickets] [PulseAudio] #898: Bluetooth sink isn't created when pulseaudio run as system daemon

Sat Jan 22 02:29:04 PST 2011

#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