pulseaudio continuously creates and frees input streams
Submitted by Dennis Schridde
Assigned to pul..@..op.org
Description
The following game is repeated over and over again, lots of times per second (Konsole has trouble printing as fast as the messages appear), which leads to 100% CPU usage in kmix and might also be responsible for the distorted sound I am experiencing:
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_06.1.analog-stereo becomes busy. D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 I: [pulseaudio] sink-input.c: Created input 1389 "ALSA Playback" on alsa_output.pci-0000_00_06.1.analog-stereo with sample spec s16le 2ch 44100Hz and channel map front-left,front-right I: [pulseaudio] sink-input.c: media.name = "ALSA Playback" I: [pulseaudio] sink-input.c: application.name = "ALSA plug-in [chrome]" I: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" I: [pulseaudio] sink-input.c: native-protocol.version = "26" I: [pulseaudio] sink-input.c: application.process.id = "2912" I: [pulseaudio] sink-input.c: application.process.user = "UUU" I: [pulseaudio] sink-input.c: application.process.host = "HHH" I: [pulseaudio] sink-input.c: application.process.binary = "chrome" I: [pulseaudio] sink-input.c: application.icon_name = "google-chrome" I: [pulseaudio] sink-input.c: window.x11.display = ":0" I: [pulseaudio] sink-input.c: application.language = "en_GB.UTF-8" I: [pulseaudio] sink-input.c: application.process.machine_id = "da20799125212121ea1810b900000015" I: [pulseaudio] sink-input.c: application.process.session_id = "da20799125212121ea1810b900000015-1337888076.451378-629967621" I: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [chrome]" I: [pulseaudio] protocol-native.c: Requested tlength=60.00 ms, minreq=14.99 ms D: [pulseaudio] protocol-native.c: Early requests mode enabled, configuring sink latency to minreq. D: [pulseaudio] protocol-native.c: Requested latency=14.99 ms, Received latency=100.14 ms D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=52992, base=4, prebuf=7940, minreq=17660 maxrewind=0 D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=52992, base=4, prebuf=7940, minreq=17660 maxrewind=0 I: [pulseaudio] protocol-native.c: Final latency 400.54 ms = 100.18 ms + 2*100.11 ms + 100.14 ms D: [alsa-sink] alsa-sink.c: Requested volume: 0: 100% 1: 100% D: [alsa-sink] alsa-sink.c: in dB: 0: 0.00 dB 1: 0.00 dB D: [alsa-sink] alsa-sink.c: Got hardware volume: 0: 100% 1: 100% D: [alsa-sink] alsa-sink.c: in dB: 0: 0.00 dB 1: 0.00 dB D: [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) D: [alsa-sink] alsa-sink.c: in dB: 0: 0.00 dB 1: 0.00 dB D: [alsa-sink] sink.c: Volume not changing D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream1389 D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. D: [alsa-sink] alsa-sink.c: Requested to rewind 17664 bytes. D: [alsa-sink] alsa-sink.c: Limited to 17380 bytes. D: [alsa-sink] alsa-sink.c: before: 4345 D: [alsa-sink] alsa-sink.c: after: 4345 D: [alsa-sink] alsa-sink.c: Rewound 17380 bytes. D: [alsa-sink] sink.c: Processing rewind... D: [alsa-sink] sink.c: latency = 1259 D: [alsa-sink] sink-input.c: Have to rewind 17380 bytes on render memblockq. D: [alsa-sink] source.c: Processing rewind... D: [alsa-sink] protocol-native.c: Underrun on 'ALSA Playback', 0 bytes in queue. D: [alsa-sink] alsa-sink.c: Requested volume: 0: 100% 1: 100% D: [alsa-sink] alsa-sink.c: in dB: 0: 0.00 dB 1: 0.00 dB D: [alsa-sink] alsa-sink.c: Got hardware volume: 0: 100% 1: 100% D: [alsa-sink] alsa-sink.c: in dB: 0: 0.00 dB 1: 0.00 dB D: [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) D: [alsa-sink] alsa-sink.c: in dB: 0: 0.00 dB 1: 0.00 dB D: [alsa-sink] sink.c: Volume not changing D: [alsa-sink] alsa-sink.c: Requested to rewind 17664 bytes. D: [alsa-sink] alsa-sink.c: Limited to 17340 bytes. D: [alsa-sink] alsa-sink.c: before: 4335 D: [alsa-sink] alsa-sink.c: after: 4335 D: [alsa-sink] alsa-sink.c: Rewound 17340 bytes. D: [alsa-sink] sink.c: Processing rewind... D: [alsa-sink] sink.c: latency = 1354 D: [alsa-sink] source.c: Processing rewind... D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_06.1.analog-stereo becomes idle, timeout in 5 seconds. D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_06.1.analog-stereo becomes idle, timeout in 5 seconds. D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum. D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream1389 I: [pulseaudio] sink-input.c: Freeing input 1389 "ALSA Playback" D: [pulseaudio] module-intended-roles.c: Not setting device for stream ALSA Playback, because it lacks role. D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_06.1.analog-stereo becomes busy. D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 I: [pulseaudio] sink-input.c: Created input 1390 "ALSA Playback" on alsa_output.pci-0000_00_06.1.analog-stereo with sample spec s16le 2ch 44100Hz and channel map front-left,front-right [...]
Versions used are Gentoo/Linux kernel 3.4.0 and PulseAudio 2.0. Audio device is: 00:06.1 Audio device: NVIDIA Corporation MCP55 High Definition Audio (rev a2) Subsystem: ASUSTeK Computer Inc. Device 81f6 Kernel driver in use: snd_hda_intel