[pulseaudio-discuss] Stuttering, pauses, and general crappiness

[Weedy]

After pulse has been running a while and my laptop has done a few
suspend/resume cycles pulse seems to have trouble starting new streams
until I cycle it with -k.

My buffer is getting "clogged" or not properly garbage collected? The
rewind seems to fail.

Hitting play in pandora, Sound took a few seconds and stuttered randomly.
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] client.c: Created
3163 "Native client (UNIX socket client)"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-dbus.c:
Interface org.PulseAudio.Core1.Client added for object
/org/pulseaudio/core1/client3163
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] client.c: Freed
3163 "Native client (UNIX socket client)"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Connection died.
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-dbus.c:
Interface org.PulseAudio.Core1.Client removed from object
/org/pulseaudio/core1/client3163
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] client.c: Created
3164 "Native client (UNIX socket client)"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-dbus.c:
Interface org.PulseAudio.Core1.Client added for object
/org/pulseaudio/core1/client3164
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Protocol version: remote 28, local 28
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Got credentials: uid=1000 gid=1000 success=1
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
SHM possible: yes
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Negotiated SHM: yes
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio]
module-augment-properties.c: Looking for .desktop file for
plugin-container
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio]
module-intended-roles.c: Not setting device for stream ALSA Playback,
because it lacks role.
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio]
module-suspend-on-idle.c: Sink
alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] memblockq.c:
memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0,
minreq=1 maxrewind=0
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] memblockq.c:
memblockq sanitized: maxlength=33554432, tlength=33554432, base=4,
prebuf=0, minreq=4 maxrewind=0
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
Created input 9715 "ALSA Playback" on
alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec s16le 2ch
44100Hz and channel map front-left,front-right
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
media.name = "ALSA Playback"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.name = "ALSA plug-in [plugin-container]"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
native-protocol.peer = "UNIX socket client"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
native-protocol.version = "28"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.process.id = "25124"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.process.user = "weedy"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.process.host = "HAF"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.process.binary = "plugin-container"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
window.x11.display = ":0.0"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.language = "en_CA.utf8"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.process.machine_id = "604fd8ab57ba18f9af54e4ab0000000d"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
module-stream-restore.id = "sink-input-by-application-name:ALSA
plug-in [plugin-container]"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Requested tlength=500.00 ms, minreq=20.00 ms
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Early requests mode enabled, configuring sink latency to minreq.
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Requested latency=20.00 ms, Received latency=186.00 ms
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] memblockq.c:
memblockq requested: maxlength=4194304, tlength=98432, base=4,
prebuf=3528, minreq=32808 maxrewind=0
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] memblockq.c:
memblockq sanitized: maxlength=4194304, tlength=98432, base=4,
prebuf=3528, minreq=32808 maxrewind=0
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Final latency 744.00 ms = 186.03 ms + 2*185.99 ms + 186.00 ms
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Latency set to 186.00ms
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: hwbuf_unused=32728
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: setting avail_min=12144
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Requested volume: 0:  41% 1:  41%
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:            in dB: 0: -23.15 dB 1: -23.15 dB
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Got hardware volume: 0:  42% 1:  42%
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:               in dB: 0: -22.50 dB 1: -22.50 dB
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Calculated software volume: 0:  98% 1:  98%
(accurate-enough=no)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:                      in dB: 0: -0.65 dB 1: -0.65 dB
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Volume not changing
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-dbus.c:
Interface org.PulseAudio.Core1.Stream added for object
/org/pulseaudio/core1/playback_stream9715
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
protocol-native.c: Requesting rewind due to end of underrun.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Requested to rewind 65536 bytes.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Limited to 28648 bytes.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: before: 7162
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: after: 7162
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Rewound 28648 bytes.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Processing rewind...
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: latency = 1408
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink-input.c: Have to rewind 28648 bytes on render memblockq.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink-input.c: Have to rewind 28648 bytes on render memblockq.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink-input.c: Have to rewind 28648 bytes on render memblockq.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
source.c: Processing rewind...
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
protocol-native.c: Implicit underrun of 'ALSA Playback'
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3716 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3608 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3600 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3540 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3540 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3472 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3464 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3404 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3400 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3340 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3336 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3236 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3232 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3180 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3176 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3120 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3116 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Found underrun 29080 bytes ago (3004 bytes ahead in playback
buffer)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Latency set to 186.00ms
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: hwbuf_unused=32728
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: setting avail_min=12144
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Requested volume: 0:  41% 1:  41%
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:            in dB: 0: -23.15 dB 1: -23.15 dB
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Got hardware volume: 0:  42% 1:  42%
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:               in dB: 0: -22.50 dB 1: -22.50 dB
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Calculated software volume: 0:  98% 1:  98%
(accurate-enough=no)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:                      in dB: 0: -0.65 dB 1: -0.65 dB
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Volume not changing
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Requested to rewind 65536 bytes.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Limited to 31748 bytes.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: before: 7937
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: after: 7937
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Rewound 31748 bytes.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Processing rewind...
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: latency = 1400
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink-input.c: Have to rewind 31748 bytes on render memblockq.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink-input.c: Have to rewind 31748 bytes on render memblockq.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
source.c: Processing rewind...
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-dbus.c:
Interface org.PulseAudio.Core1.Stream removed from object
/org/pulseaudio/core1/playback_stream9715
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
Freeing input 9715 "ALSA Playback"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio]
module-intended-roles.c: Not setting device for stream ALSA Playback,
because it lacks role.
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio]
module-suspend-on-idle.c: Sink
alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] memblockq.c:
memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0,
minreq=1 maxrewind=0
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] memblockq.c:
memblockq sanitized: maxlength=33554432, tlength=33554432, base=4,
prebuf=0, minreq=4 maxrewind=0
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
Created input 9716 "ALSA Playback" on
alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec s16le 2ch
44100Hz and channel map front-left,front-right
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
media.name = "ALSA Playback"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.name = "ALSA plug-in [plugin-container]"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
native-protocol.peer = "UNIX socket client"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
native-protocol.version = "28"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.process.id = "25124"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.process.user = "weedy"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.process.host = "HAF"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.process.binary = "plugin-container"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
window.x11.display = ":0.0"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.language = "en_CA.utf8"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
application.process.machine_id = "604fd8ab57ba18f9af54e4ab0000000d"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] sink-input.c:
module-stream-restore.id = "sink-input-by-application-name:ALSA
plug-in [plugin-container]"
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Requested tlength=500.00 ms, minreq=20.00 ms
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Early requests mode enabled, configuring sink latency to minreq.
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Requested latency=20.00 ms, Received latency=186.00 ms
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] memblockq.c:
memblockq requested: maxlength=4194304, tlength=98432, base=4,
prebuf=65628, minreq=32808 maxrewind=0
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] memblockq.c:
memblockq sanitized: maxlength=4194304, tlength=98432, base=4,
prebuf=65628, minreq=32808 maxrewind=0
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-native.c:
Final latency 744.00 ms = 186.03 ms + 2*185.99 ms + 186.00 ms
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Latency set to 186.00ms
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: hwbuf_unused=32728
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: setting avail_min=12144
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Requested volume: 0:  41% 1:  41%
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:            in dB: 0: -23.15 dB 1: -23.15 dB
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Got hardware volume: 0:  42% 1:  42%
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:               in dB: 0: -22.50 dB 1: -22.50 dB
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Calculated software volume: 0:  98% 1:  98%
(accurate-enough=no)
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:                      in dB: 0: -0.65 dB 1: -0.65 dB
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Volume not changing
Oct 30 17:23:09 haf pulseaudio[3815]: [pulseaudio] protocol-dbus.c:
Interface org.PulseAudio.Core1.Stream added for object
/org/pulseaudio/core1/playback_stream9716
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
protocol-native.c: Requesting rewind due to end of underrun.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Requested to rewind 65536 bytes.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Limited to 30224 bytes.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: before: 7556
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: after: 7556
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Rewound 30224 bytes.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: Processing rewind...
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink.c: latency = 1198
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink-input.c: Have to rewind 30224 bytes on render memblockq.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink-input.c: Have to rewind 30224 bytes on render memblockq.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
sink-input.c: Have to rewind 30224 bytes on render memblockq.
Oct 30 17:23:09 haf pulseaudio[3815]: [alsa-sink-STAC9205 Analog]
source.c: Processing rewind...


Closed FF, issued pulseaudio -k, waited like 10 seconds then started
pulse again. Perfect audio.
Oct 30 17:25:43 haf pulseaudio[25725]: [pulseaudio]
module-suspend-on-idle.c: Sink
alsa_output.pci-0000_00_1b.0.analog-stereo idle for too long,
suspending ...
Oct 30 17:25:43 haf pulseaudio[25725]: [pulseaudio] sink.c: Suspend
cause of sink alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0004,
suspending
Oct 30 17:25:43 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Device suspended...
Oct 30 17:25:43 haf pulseaudio[25725]: [pulseaudio] core.c: Hmm, no
streams around, trying to vacuum.
Oct 30 17:25:43 haf pulseaudio[25725]: [pulseaudio]
module-udev-detect.c: /dev/snd/controlC0 is accessible: yes
Oct 30 17:25:43 haf pulseaudio[25725]: [pulseaudio]
module-udev-detect.c: Resuming all sinks and sources of card
alsa_card.pci-0000_00_1b.0.
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] client.c: Created
4 "Native client (UNIX socket client)"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-dbus.c:
Interface org.PulseAudio.Core1.Client added for object
/org/pulseaudio/core1/client4
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] client.c: Freed 4
"Native client (UNIX socket client)"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-native.c:
Connection died.
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-dbus.c:
Interface org.PulseAudio.Core1.Client removed from object
/org/pulseaudio/core1/client4
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] client.c: Created
5 "Native client (UNIX socket client)"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-dbus.c:
Interface org.PulseAudio.Core1.Client added for object
/org/pulseaudio/core1/client5
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-native.c:
Protocol version: remote 28, local 28
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-native.c:
Got credentials: uid=1000 gid=1000 success=1
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-native.c:
SHM possible: yes
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-native.c:
Negotiated SHM: yes
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio]
module-augment-properties.c: Looking for .desktop file for
plugin-container
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio]
module-intended-roles.c: Not setting device for stream ALSA Playback,
because it lacks role.
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio]
module-suspend-on-idle.c: Sink
alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink.c: Suspend
cause of sink alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0000,
resuming
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] reserve-wrap.c:
Successfully acquired reservation lock on device 'Audio0'
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Trying resume...
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-util.c: Trying to disable ALSA period wakeups, using timers only
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-util.c: Maximum hw buffer size is 371 ms
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-util.c: Set buffer size first (to 16384 samples), period size
second (to 8192 samples).
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-util.c: ALSA period wakeups disabled
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: hwbuf_unused=0
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: setting avail_min=16165
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Time scheduling watermark is 20.00ms
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Resumed successfully...
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Starting playback.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Cutting sleep time for the initial iterations by half.
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio]
module-suspend-on-idle.c: Sink
alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5
seconds.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Cutting sleep time for the initial iterations by half.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Cutting sleep time for the initial iterations by half.
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] memblockq.c:
memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0,
minreq=1 maxrewind=0
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] memblockq.c:
memblockq sanitized: maxlength=33554432, tlength=33554432, base=4,
prebuf=0, minreq=4 maxrewind=0
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
Created input 10 "ALSA Playback" on
alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec s16le 2ch
44100Hz and channel map front-left,front-right
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
media.name = "ALSA Playback"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
application.name = "ALSA plug-in [plugin-container]"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
native-protocol.peer = "UNIX socket client"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
native-protocol.version = "28"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
application.process.id = "26565"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
application.process.user = "weedy"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
application.process.host = "HAF"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
application.process.binary = "plugin-container"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
window.x11.display = ":0.0"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
application.language = "en_CA.utf8"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
application.process.machine_id = "604fd8ab57ba18f9af54e4ab0000000d"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] sink-input.c:
module-stream-restore.id = "sink-input-by-application-name:ALSA
plug-in [plugin-container]"
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-native.c:
Requested tlength=500.00 ms, minreq=20.00 ms
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-native.c:
Early requests mode enabled, configuring sink latency to minreq.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Cutting sleep time for the initial iterations by half.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Cutting sleep time for the initial iterations by half.
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-native.c:
Requested latency=20.00 ms, Received latency=20.00 ms
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] memblockq.c:
memblockq requested: maxlength=4194304, tlength=88200, base=4,
prebuf=3528, minreq=3528 maxrewind=0
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] memblockq.c:
memblockq sanitized: maxlength=4194304, tlength=88200, base=4,
prebuf=3528, minreq=3528 maxrewind=0
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-native.c:
Final latency 520.00 ms = 460.00 ms + 2*20.00 ms + 20.00 ms
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Cutting sleep time for the initial iterations by half.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Latency set to 20.00ms
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: hwbuf_unused=62008
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: setting avail_min=15944
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Requesting rewind due to latency change.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Requested volume: 0:  41% 1:  41%
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:            in dB: 0: -23.15 dB 1: -23.15 dB
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Got hardware volume: 0:  41% 1:  41%
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:               in dB: 0: -23.10 dB 1: -23.10 dB
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Calculated software volume: 0: 100% 1: 100%
(accurate-enough=yes)
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c:                      in dB: 0: -0.05 dB 1: -0.05 dB
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
sink.c: Volume not changing
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Requested to rewind 65536 bytes.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Limited to 64948 bytes.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: before: 16237
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: after: 16237
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Rewound 64948 bytes.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
sink.c: Processing rewind...
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
sink.c: latency = 1413
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
sink-input.c: Have to rewind 64948 bytes on render memblockq.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
source.c: Processing rewind...
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] core-subscribe.c:
Dropped redundant event due to change event.
Oct 30 17:25:45 haf pulseaudio[25725]: [pulseaudio] protocol-dbus.c:
Interface org.PulseAudio.Core1.Stream added for object
/org/pulseaudio/core1/playback_stream10
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
protocol-native.c: Requesting rewind due to end of underrun.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Requested to rewind 65536 bytes.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Limited to 2760 bytes.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: before: 690
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: after: 690
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Rewound 2760 bytes.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
sink.c: Processing rewind...
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
sink.c: latency = 1408
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
sink-input.c: Have to rewind 2760 bytes on render memblockq.
Oct 30 17:25:45 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
source.c: Processing rewind...
Oct 30 17:25:46 haf pulseaudio[25725]: [alsa-sink-STAC9205 Analog]
alsa-sink.c: Scheduling delay of 2.53ms > 1.76ms, you might want to
investigate this to improve latency...