I: main.c: setrlimit(RLIMIT_NICE, (31, 31)) failed: Operation not permitted I: main.c: setrlimit(RLIMIT_RTPRIO, (9, 9)) failed: Operation not permitted D: core-rtclock.c: Timer slack is set to 50 us. D: core-util.c: RealtimeKit worked. I: core-util.c: Successfully gained nice level -11. I: main.c: This is PulseAudio 0.9.21-32-g8478 D: main.c: Compilation host: i686-pc-linux-gnu D: main.c: Compilation CFLAGS: -g -O2 -Wall -W -Wextra -pipe -Wno-long-long -Winline -Wvla -Wno-overlength-strings -Wunsafe-loop-optimizations -Wundef -Wformat=2 -Wlogical-op -Wsign-compare -Wformat-security -Wmissing-include-dirs -Wformat-nonliteral -Wold-style-definition -Wpointer-arith -Winit-self -Wdeclaration-after-statement -Wfloat-equal -Wmissing-prototypes -Wstrict-prototypes -Wredundant-decls -Wmissing-declarations -Wmissing-noreturn -Wshadow -Wendif-labels -Wcast-align -Wstrict-aliasing=2 -Wwrite-strings -Wno-unused-parameter -ffast-math -Wp,-D_FORTIFY_SOURCE=2 -fno-common -fdiagnostics-show-option D: main.c: Running on host: Linux i686 2.6.32-23-generic #37-Ubuntu SMP Fri Jun 11 07:54:58 UTC 2010 D: main.c: Found 4 CPUs. I: main.c: Page size is 4096 bytes D: main.c: Compiled with Valgrind support: no D: main.c: Running in valgrind mode: no D: main.c: Running in VM: no D: main.c: Optimised build: yes D: main.c: All asserts enabled. I: main.c: Machine ID is fb0b905abe6de81565cb366c4af72b0f. I: main.c: Session ID is fb0b905abe6de81565cb366c4af72b0f-1278531946.297669-974301751. I: main.c: Using runtime directory /home/mike/.pulse/fb0b905abe6de81565cb366c4af72b0f-runtime. I: main.c: Using state directory /home/mike/.pulse. I: main.c: Using modules directory /usr/local/lib/pulse-0.9.21/modules. I: main.c: Running in system mode: no I: main.c: Fresh high-resolution timers available! Enjoy ol' chap! I: cpu-x86.c: CPU flags: CMOV MMX SSE SSE2 SSE3 SSSE3 I: svolume_mmx.c: Initialising MMX optimized functions. I: remap_mmx.c: Initialising MMX optimized remappers. I: svolume_sse.c: Initialising SSE2 optimized functions. I: remap_sse.c: Initialising SSE2 optimized remappers. I: sconv_sse.c: Initialising SSE2 optimized conversions. D: memblock.c: Using shared memory pool with 1024 slots of size 64.0 KiB each, total size is 64.0 MiB, maximum usable slot size is 65496 D: database-tdb.c: Opened TDB database '/home/mike/.pulse/fb0b905abe6de81565cb366c4af72b0f-device-volumes.tdb' I: module-device-restore.c: Sucessfully opened database file '/home/mike/.pulse/fb0b905abe6de81565cb366c4af72b0f-device-volumes'. I: module.c: Loaded "module-device-restore" (index: #0; argument: ""). D: database-tdb.c: Opened TDB database '/home/mike/.pulse/fb0b905abe6de81565cb366c4af72b0f-stream-volumes.tdb' I: module-stream-restore.c: Sucessfully opened database file '/home/mike/.pulse/fb0b905abe6de81565cb366c4af72b0f-stream-volumes'. I: module.c: Loaded "module-stream-restore" (index: #1; argument: ""). D: database-tdb.c: Opened TDB database '/home/mike/.pulse/fb0b905abe6de81565cb366c4af72b0f-card-database.tdb' I: module-card-restore.c: Sucessfully opened database file '/home/mike/.pulse/fb0b905abe6de81565cb366c4af72b0f-card-database'. I: module.c: Loaded "module-card-restore" (index: #2; argument: ""). I: module.c: Loaded "module-augment-properties" (index: #3; argument: ""). D: alsa-util.c: Trying Filter_RateConvert with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Managed to open Filter_RateConvert D: alsa-util.c: Maximum hw buffer size is 21824 ms D: alsa-util.c: Set buffer size first (to 4800 samples), period size second (to 1200 samples). I: alsa-sink.c: Successfully opened device Filter_RateConvert. I: alsa-sink.c: Successfully enabled mmap() mode. I: sink.c: Created sink 0 "SPDIF" with sample spec s16le 6ch 48000Hz and channel map front-left,front-right,rear-left,rear-right,front-center,lfe I: sink.c: alsa.resolution_bits = "16" I: sink.c: device.api = "alsa" I: sink.c: device.class = "sound" I: sink.c: alsa.class = "generic" I: sink.c: alsa.subclass = "generic-mix" I: sink.c: alsa.name = "Filter_A52Encode" I: sink.c: alsa.id = "Filter_A52Encode" I: sink.c: alsa.subdevice = "0" I: sink.c: alsa.subdevice_name = "Filter_A52Encode" I: sink.c: alsa.device = "0" I: sink.c: device.string = "Filter_RateConvert" I: sink.c: device.buffering.buffer_size = "55296" I: sink.c: device.buffering.fragment_size = "18432" I: sink.c: device.access_mode = "mmap" I: sink.c: device.description = "SPDIF" I: sink.c: device.icon_name = "audio-card" D: core-subscribe.c: Dropped redundant event due to change event. I: source.c: Created source 0 "SPDIF.monitor" with sample spec s16le 6ch 48000Hz and channel map front-left,front-right,rear-left,rear-right,front-center,lfe I: source.c: device.description = "Monitor of SPDIF" I: source.c: device.class = "monitor" I: source.c: device.icon_name = "audio-input-microphone" I: alsa-sink.c: Using 3.0 fragments of size 18432 bytes (32.00ms), buffer size is 55296 bytes (96.00ms) D: alsa-sink.c: hwbuf_unused=0 D: alsa-sink.c: setting avail_min=1 D: alsa-util.c: snd_pcm_dump(): D: alsa-util.c: Rate conversion PCM (48000) D: alsa-util.c: Converter: libspeex (builtin) D: alsa-util.c: Protocol version: 10002 D: alsa-util.c: Its setup is: D: alsa-util.c: stream : PLAYBACK D: alsa-util.c: access : MMAP_INTERLEAVED D: alsa-util.c: format : S16_LE D: alsa-util.c: subformat : STD D: alsa-util.c: channels : 6 D: alsa-util.c: rate : 48000 D: alsa-util.c: exact rate : 48000 (48000/1) D: alsa-util.c: msbits : 16 D: alsa-util.c: buffer_size : 4608 D: alsa-util.c: period_size : 1536 D: alsa-util.c: period_time : 32000 D: alsa-util.c: tstamp_mode : ENABLE D: alsa-util.c: period_step : 1 D: alsa-util.c: avail_min : 1536 D: alsa-util.c: period_event : 1 D: alsa-util.c: start_threshold : -1 D: alsa-util.c: stop_threshold : 1207959552 D: alsa-util.c: silence_threshold: 0 D: alsa-util.c: silence_size : 0 D: alsa-util.c: boundary : 1207959552 D: alsa-util.c: Slave: A52 Output Plugin D: alsa-util.c: Its setup is: D: alsa-util.c: stream : PLAYBACK D: alsa-util.c: access : MMAP_INTERLEAVED D: alsa-util.c: format : S16_LE D: alsa-util.c: subformat : STD D: alsa-util.c: channels : 6 D: alsa-util.c: rate : 48000 D: alsa-util.c: exact rate : 48000 (48000/1) D: alsa-util.c: msbits : 16 D: alsa-util.c: buffer_size : 4608 D: alsa-util.c: period_size : 1536 D: alsa-util.c: period_time : 32000 D: alsa-util.c: tstamp_mode : ENABLE D: alsa-util.c: period_step : 1 D: alsa-util.c: avail_min : 1536 D: alsa-util.c: period_event : 1 D: alsa-util.c: start_threshold : -1 D: alsa-util.c: stop_threshold : 1207959552 D: alsa-util.c: silence_threshold: 0 D: alsa-util.c: silence_size : 0 D: alsa-util.c: boundary : 1207959552 D: alsa-sink.c: Thread starting up D: core-util.c: RealtimeKit worked. I: core-util.c: Successfully enabled SCHED_RR scheduling for thread, with priority 5. I: alsa-sink.c: Starting playback. I: (alsa-lib)pcm_hw.c: SNDRV_PCM_IOCTL_START failed (-77) I: module.c: Loaded "module-alsa-sink" (index: #4; argument: "device=Filter_RateConvert rate=48000 channels=6 tsched=0 sink_properties=device.description=SPDIF sink_name=SPDIF"). D: cli-command.c: Checking for existance of '/usr/local/lib/pulse-0.9.21/modules/module-bluetooth-discover.so': failure D: cli-command.c: Checking for existance of '/usr/local/lib/pulse-0.9.21/modules/module-esound-protocol-unix.so': success I: module.c: Loaded "module-esound-protocol-unix" (index: #5; argument: ""). I: module.c: Loaded "module-native-protocol-unix" (index: #6; argument: ""). D: cli-command.c: Checking for existance of '/usr/local/lib/pulse-0.9.21/modules/module-gconf.so': failure I: module-default-device-restore.c: No previous default sink setting, ignoring. I: module-default-device-restore.c: No previous default source setting, ignoring. I: module.c: Loaded "module-default-device-restore" (index: #7; argument: ""). I: module.c: Loaded "module-rescue-streams" (index: #8; argument: ""). I: module.c: Loaded "module-always-sink" (index: #9; argument: ""). I: module.c: Loaded "module-intended-roles" (index: #10; argument: ""). D: module-suspend-on-idle.c: Sink SPDIF becomes idle, timeout in 5 seconds. I: module.c: Loaded "module-suspend-on-idle" (index: #11; argument: ""). D: dbus-util.c: Successfully connected to D-Bus system bus 457e3f513475d2c2ef59306c4c34d967 as :1.94 I: client.c: Created 0 "ConsoleKit Session /org/freedesktop/ConsoleKit/Session1" D: module-console-kit.c: Added new session /org/freedesktop/ConsoleKit/Session1 I: module.c: Loaded "module-console-kit" (index: #12; argument: ""). I: module.c: Loaded "module-position-event-sounds" (index: #13; argument: ""). D: dbus-util.c: Successfully connected to D-Bus session bus 4b2891bfe65d39f1b004719f4c34d96a as :1.155 D: main.c: Got org.pulseaudio.Server! I: main.c: Daemon startup complete. D: module-console-kit.c: dbus: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=NameAcquired D: core-subscribe.c: Dropped redundant event due to change event. I: module-suspend-on-idle.c: Sink SPDIF idle for too long, suspending ... D: sink.c: Suspend cause of sink SPDIF is 0x0004, suspending I: alsa-sink.c: Device suspended... I: client.c: Created 1 "Native client (UNIX socket client)" D: protocol-native.c: Protocol version: remote 16, local 16 I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 D: protocol-native.c: SHM possible: yes D: protocol-native.c: Negotiated SHM: yes D: module-augment-properties.c: Looking for .desktop file for rhythmbox I: module-stream-restore.c: Restoring mute state for sink input sink-input-by-media-role:music. D: resampler.c: Channel matrix: D: resampler.c: I00 I01 D: resampler.c: +------------ D: resampler.c: O00 | 1.000 0.000 D: resampler.c: O01 | 0.000 1.000 D: resampler.c: O02 | 1.000 0.000 D: resampler.c: O03 | 0.000 1.000 D: resampler.c: O04 | 0.500 0.500 D: resampler.c: O05 | 0.000 0.000 I: remap.c: Using generic matrix remapping I: resampler.c: Using resampler 'speex-float-3' I: resampler.c: Using float32le as working format. I: resampler.c: Choosing speex quality setting 3. D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=12, prebuf=0, minreq=1 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=33554436, tlength=33554436, base=12, prebuf=0, minreq=12 maxrewind=0 I: sink-input.c: Created input 0 "Playback Stream" on SPDIF with sample spec s16le 2ch 44100Hz and channel map front-left,front-right I: sink-input.c: media.name = "Playback Stream" I: sink-input.c: application.name = "Rhythmbox" I: sink-input.c: native-protocol.peer = "UNIX socket client" I: sink-input.c: native-protocol.version = "16" I: sink-input.c: media.role = "music" I: sink-input.c: application.process.id = "3478" I: sink-input.c: application.process.user = "mike" I: sink-input.c: application.process.host = "Holly" I: sink-input.c: application.process.binary = "rhythmbox" I: sink-input.c: application.icon_name = "rhythmbox" I: sink-input.c: window.x11.display = ":1.0" I: sink-input.c: application.language = "en_GB.UTF-8" I: sink-input.c: application.process.machine_id = "fb0b905abe6de81565cb366c4af72b0f" I: sink-input.c: application.process.session_id = "fb0b905abe6de81565cb366c4af72b0f-1278531946.297669-974301751" I: sink-input.c: module-stream-restore.id = "sink-input-by-media-role:music" I: protocol-native.c: Requested tlength=200.00 ms, minreq=10.00 ms D: protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. D: memblockq.c: memblockq requested: maxlength=4194304, tlength=20464, base=4, prebuf=0, minreq=1764 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=20464, base=4, prebuf=0, minreq=1764 maxrewind=0 I: protocol-native.c: Final latency 212.01 ms = 96.01 ms + 2*10.00 ms + 96.00 ms D: sink-input.c: Requesting rewind due to uncorking D: sink.c: Suspend cause of sink SPDIF is 0x0000, resuming I: alsa-sink.c: Trying resume... D: alsa-util.c: Maximum hw buffer size is 21824 ms D: alsa-util.c: Set buffer size first (to 4608 samples), period size second (to 1536 samples). D: alsa-sink.c: hwbuf_unused=0 D: alsa-sink.c: setting avail_min=1 I: alsa-sink.c: Resumed successfully... I: alsa-sink.c: Starting playback. I: (alsa-lib)pcm_hw.c: SNDRV_PCM_IOCTL_START failed (-77) D: module-suspend-on-idle.c: Sink SPDIF becomes busy. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. E: alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! E: alsa-sink.c: Most likely this is a bug in the ALSA driver '(null)'. Please report this issue to the ALSA developers. E: alsa-sink.c: We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail() returned 0 or another value < min_avail. D: sink-input.c: Requesting rewind due to corking D: module-suspend-on-idle.c: Sink SPDIF becomes idle, timeout in 5 seconds. D: sink-input.c: Requesting rewind due to uncorking D: module-suspend-on-idle.c: Sink SPDIF becomes busy. D: protocol-native.c: Requesting rewind due to rewrite. D: sink-input.c: Requesting rewind due to corking D: module-suspend-on-idle.c: Sink SPDIF becomes idle, timeout in 5 seconds. D: alsa-sink.c: Requested to rewind 55296 bytes. D: alsa-sink.c: Limited to 55248 bytes. D: alsa-sink.c: before: 4604 E: alsa-sink.c: snd_pcm_rewind() failed: Unknown error 4604 D: alsa-sink.c: process_rewind: Unknown error 4604 E: alsa-sink.c: process_rewind: Unknown error 4604 D: module-suspend-on-idle.c: Sink SPDIF becomes idle, timeout in 5 seconds. D: core.c: Hmm, no streams around, trying to vacuum. I: sink-input.c: Freeing input 0 "Playback Stream" I: client.c: Freed 1 "Rhythmbox" I: protocol-native.c: Connection died. I: module.c: Unloading "module-alsa-sink" (index: #4). D: module-always-sink.c: Autoloading null-sink as no other sinks detected. I: sink.c: Created sink 1 "auto_null" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right I: sink.c: device.description = "Dummy Output" I: sink.c: device.class = "abstract" I: sink.c: device.icon_name = "audio-card" I: source.c: Created source 1 "auto_null.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right I: source.c: device.description = "Monitor of Dummy Output" I: source.c: device.class = "monitor" I: source.c: device.icon_name = "audio-input-microphone" D: module-null-sink.c: Thread starting up D: module-suspend-on-idle.c: Sink auto_null becomes idle, timeout in 5 seconds. I: module.c: Loaded "module-null-sink" (index: #14; argument: "sink_name=auto_null sink_properties='device.description="Dummy Output"'"). D: module-rescue-streams.c: No sink inputs to move away. D: module-rescue-streams.c: No source outputs to move away. D: core-subscribe.c: Dropped redundant event due to change event. D: alsa-sink.c: Thread shutting down I: sink.c: Freeing sink 0 "SPDIF" I: source.c: Freeing source 0 "SPDIF.monitor" I: module.c: Unloaded "module-alsa-sink" (index: #4). D: core-subscribe.c: Dropped redundant event due to change event. I: module-suspend-on-idle.c: Sink auto_null idle for too long, suspending ... D: sink.c: Suspend cause of sink auto_null is 0x0004, suspending I: client.c: Created 2 "Native client (UNIX socket client)" D: protocol-native.c: Protocol version: remote 16, local 16 I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 D: protocol-native.c: SHM possible: yes D: protocol-native.c: Negotiated SHM: yes D: module-augment-properties.c: Looking for .desktop file for rhythmbox I: module-stream-restore.c: Restoring mute state for sink input sink-input-by-media-role:music. D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 I: sink-input.c: Created input 1 "Playback Stream" on auto_null with sample spec s16le 2ch 44100Hz and channel map front-left,front-right I: sink-input.c: media.name = "Playback Stream" I: sink-input.c: application.name = "Rhythmbox" I: sink-input.c: native-protocol.peer = "UNIX socket client" I: sink-input.c: native-protocol.version = "16" I: sink-input.c: media.role = "music" I: sink-input.c: application.process.id = "3987" I: sink-input.c: application.process.user = "mike" I: sink-input.c: application.process.host = "Holly" I: sink-input.c: application.process.binary = "rhythmbox" I: sink-input.c: application.icon_name = "rhythmbox" I: sink-input.c: window.x11.display = ":1.0" I: sink-input.c: application.language = "en_GB.UTF-8" I: sink-input.c: application.process.machine_id = "fb0b905abe6de81565cb366c4af72b0f" I: sink-input.c: application.process.session_id = "fb0b905abe6de81565cb366c4af72b0f-1278531946.297669-974301751" I: sink-input.c: module-stream-restore.id = "sink-input-by-media-role:music" I: protocol-native.c: Requested tlength=200.00 ms, minreq=10.00 ms D: protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. D: memblockq.c: memblockq requested: maxlength=4194304, tlength=19404, base=4, prebuf=0, minreq=1764 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=19404, base=4, prebuf=0, minreq=1764 maxrewind=0 I: protocol-native.c: Final latency 200.00 ms = 90.00 ms + 2*10.00 ms + 90.00 ms D: sink-input.c: Requesting rewind due to uncorking D: sink.c: Suspend cause of sink auto_null is 0x0000, resuming D: module-suspend-on-idle.c: Sink auto_null becomes busy. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: sink-input.c: Requesting rewind due to corking D: module-suspend-on-idle.c: Sink auto_null becomes idle, timeout in 5 seconds. D: sink-input.c: Requesting rewind due to uncorking D: module-suspend-on-idle.c: Sink auto_null becomes busy. D: protocol-native.c: Requesting rewind due to rewrite. D: protocol-native.c: Requesting rewind due to rewrite. D: sink-input.c: Requesting rewind due to corking D: module-suspend-on-idle.c: Sink auto_null becomes idle, timeout in 5 seconds. I: main.c: Got signal SIGINT. I: main.c: Exiting. I: main.c: Daemon shutdown initiated. I: module.c: Unloading "module-device-restore" (index: #0). I: module.c: Unloaded "module-device-restore" (index: #0). I: module.c: Unloading "module-stream-restore" (index: #1). I: module.c: Unloaded "module-stream-restore" (index: #1). I: module.c: Unloading "module-card-restore" (index: #2). I: module.c: Unloaded "module-card-restore" (index: #2). I: module.c: Unloading "module-augment-properties" (index: #3). I: module.c: Unloaded "module-augment-properties" (index: #3). I: module.c: Unloading "module-esound-protocol-unix" (index: #5). I: module.c: Unloaded "module-esound-protocol-unix" (index: #5). I: module.c: Unloading "module-native-protocol-unix" (index: #6). D: module-suspend-on-idle.c: Sink auto_null becomes idle, timeout in 5 seconds. D: module-null-sink.c: Requested to rewind 1764000 bytes. D: core.c: Hmm, no streams around, trying to vacuum. D: sink.c: Processing rewind... D: source.c: Processing rewind... D: module-null-sink.c: Rewound 9712 bytes. I: sink-input.c: Freeing input 1 "Playback Stream" I: client.c: Freed 2 "Rhythmbox" I: module.c: Unloaded "module-native-protocol-unix" (index: #6). I: module.c: Unloading "module-default-device-restore" (index: #7). I: module.c: Unloaded "module-default-device-restore" (index: #7). I: module.c: Unloading "module-rescue-streams" (index: #8). I: module.c: Unloaded "module-rescue-streams" (index: #8). I: module.c: Unloading "module-always-sink" (index: #9). I: module.c: Unloaded "module-always-sink" (index: #9). I: module.c: Unloading "module-intended-roles" (index: #10). I: module.c: Unloaded "module-intended-roles" (index: #10). I: module.c: Unloading "module-suspend-on-idle" (index: #11). I: module.c: Unloaded "module-suspend-on-idle" (index: #11). I: module.c: Unloading "module-console-kit" (index: #12). D: module-console-kit.c: Removing session /org/freedesktop/ConsoleKit/Session1 I: client.c: Freed 0 "ConsoleKit Session /org/freedesktop/ConsoleKit/Session1" I: module.c: Unloaded "module-console-kit" (index: #12). I: module.c: Unloading "module-position-event-sounds" (index: #13). I: module.c: Unloaded "module-position-event-sounds" (index: #13). I: module.c: Unloading "module-null-sink" (index: #14). D: core-subscribe.c: Dropped redundant event due to change event. D: module-null-sink.c: Thread shutting down I: sink.c: Freeing sink 1 "auto_null" I: source.c: Freeing source 1 "auto_null.monitor" I: module.c: Unloaded "module-null-sink" (index: #14). I: main.c: Daemon terminated.