[pulseaudio-discuss] huge skype sound events delay

Julian Sikorski belegdol at gmail.com
Tue Dec 11 12:07:14 PST 2012


Hi,

I posted this on the skype community forums too, but I thought it might
be wise to attack the problem on different fronts. Basically, after
upgrading to Fedora 18, skype sounds produce a ridiculously long delay,
unless pavucontrol is started side-by side. Relevant logs are attached.
The versions of the packages are the following:
pulseaudio-2.1-4.fc18.x86_64
alsa-lib-1.0.26-2.fc18.x86_64
alsa-lib-1.0.26-2.fc18.i686
skype-4.1.0.20-fc16.i586
Any ideas on what might be going on would be appreciated.

Best regards,
Julian
-------------- next part --------------
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ...
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0004, suspending
I: [alsa-sink] alsa-sink.c: Device suspended...
D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC0 is accessible: yes
I: [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-media-role:event.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream Event Sound, because already set.
I: [pulseaudio] sink-input.c: Trying to change sample rate
I: [pulseaudio] alsa-sink.c: Updating rate for device front:0, new rate is 48000
I: [pulseaudio] sink.c: Changed sampling rate successfully
I: [pulseaudio] sink-input.c: Rate changed to 48000 Hz
I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:event.
I: [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-media-role:event.
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0000, resuming
D: [pulseaudio] reserve-wrap.c: Failed to acquire reservation lock on device 'Audio0': Błąd wejścia/wyjścia
I: [alsa-sink] alsa-sink.c: Trying resume...
I: [alsa-sink] alsa-util.c: Trying to disable ALSA period wakeups, using timers only
D: [alsa-sink] alsa-util.c: Maximum hw buffer size is 21845 ms
D: [alsa-sink] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples).
I: [alsa-sink] alsa-util.c: ALSA period wakeups disabled
D: [alsa-sink] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink] alsa-sink.c: setting avail_min=88009
D: [alsa-sink] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink] alsa-sink.c: setting avail_min=87319
D: [alsa-sink] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink] alsa-sink.c: setting avail_min=87319
I: [alsa-sink] alsa-sink.c: Time scheduling watermark is 18,38ms
I: [alsa-sink] alsa-sink.c: Resumed successfully...
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
I: [alsa-sink] alsa-sink.c: Starting playback.
D: [alsa-sink] ratelimit.c: 2101 events suppressed
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy.
I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] resampler.c: Channel matrix:
D: [pulseaudio] resampler.c:        I00 
D: [pulseaudio] resampler.c:     +------
D: [pulseaudio] resampler.c: O00 | 1,000
D: [pulseaudio] resampler.c: O01 | 1,000
I: [pulseaudio] remap_sse.c: Using SSE mono to stereo remapping
I: [pulseaudio] resampler.c: Using resampler 'copy'
I: [pulseaudio] resampler.c: Using s16le as working format.
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 3 "Event Sound" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec s16le 1ch 48000Hz and channel map mono
I: [pulseaudio] sink-input.c:     window.icon_name = "skype"
I: [pulseaudio] sink-input.c:     application.icon_name = "skype"
I: [pulseaudio] sink-input.c:     media.role = "event"
I: [pulseaudio] sink-input.c:     media.name = "Event Sound"
I: [pulseaudio] sink-input.c:     application.name = "Skype"
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 = "2948"
I: [pulseaudio] sink-input.c:     application.process.user = "julas"
I: [pulseaudio] sink-input.c:     application.process.host = "snowball2"
I: [pulseaudio] sink-input.c:     application.process.binary = "skype"
I: [pulseaudio] sink-input.c:     application.language = "pl_PL.utf8"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "98d51596bca05b5c8d3ab7dd0000000d"
I: [pulseaudio] sink-input.c:     application.process.session_id = "98d51596bca05b5c8d3ab7dd0000000d-1355255869.676551-1599849202"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-media-role:event"
I: [pulseaudio] protocol-native.c: Requested tlength=20,02 ms, minreq=20,00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Requested latency=0,00 ms, Received latency=0,50 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=3888, base=2, prebuf=1970, minreq=1920 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=3888, base=2, prebuf=1970, minreq=1920 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 41,00 ms = 0,50 ms + 2*20,00 ms + 0,50 ms
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink] alsa-sink.c: Latency set to 0,50ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352704
D: [alsa-sink] alsa-sink.c: setting avail_min=88189
D: [alsa-sink] alsa-sink.c: Requesting rewind due to latency change.
D: [alsa-sink] alsa-sink.c: Requested volume: 0:  69% 1:  69%
D: [alsa-sink] alsa-sink.c:            in dB: 0: -9,67 dB 1: -9,67 dB
D: [alsa-sink] alsa-sink.c: Got hardware volume: 0:  69% 1:  69%
D: [alsa-sink] alsa-sink.c:               in dB: 0: -9,60 dB 1: -9,60 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,07 dB 1: -0,07 dB
D: [alsa-sink] sink.c: Volume not changing
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 352192 bytes.
D: [alsa-sink] alsa-sink.c: before: 88048
D: [alsa-sink] alsa-sink.c: after: 88048
D: [alsa-sink] alsa-sink.c: Rewound 352192 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 1548
D: [alsa-sink] sink-input.c: Have to rewind 352192 bytes on render memblockq.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream3
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
I: [alsa-sink] alsa-sink.c: Underrun!
I: [alsa-sink] alsa-sink.c: Increasing minimal latency to 1,00 ms
D: [alsa-sink] alsa-sink.c: Latency set to 1,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352608
D: [alsa-sink] alsa-sink.c: setting avail_min=88177
D: [alsa-sink] protocol-native.c: max_request changed, trying to update from 3888 to 3936.
D: [alsa-sink] protocol-native.c: Notifying client about increased tlength
D: [alsa-sink] alsa-sink.c: Latency set to 1,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352608
D: [alsa-sink] alsa-sink.c: setting avail_min=88177
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0004, suspending
I: [alsa-sink] alsa-sink.c: Device suspended...
I: [pulseaudio] alsa-sink.c: Updating rate for device front:0, new rate is 48000
I: [pulseaudio] sink.c: Changed sampling rate successfully
D: [alsa-sink] sink-input.c: Requesting rewind due to uncorking
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0000, resuming
D: [pulseaudio] reserve-wrap.c: Failed to acquire reservation lock on device 'Audio0': Błąd wejścia/wyjścia
I: [alsa-sink] alsa-sink.c: Trying resume...
I: [alsa-sink] alsa-util.c: Trying to disable ALSA period wakeups, using timers only
D: [alsa-sink] alsa-util.c: Maximum hw buffer size is 21845 ms
D: [alsa-sink] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples).
I: [alsa-sink] alsa-util.c: ALSA period wakeups disabled
D: [alsa-sink] alsa-sink.c: Latency set to 1,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352608
D: [alsa-sink] alsa-sink.c: setting avail_min=88177
D: [alsa-sink] alsa-sink.c: Latency set to 0,50ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352704
D: [alsa-sink] alsa-sink.c: setting avail_min=88189
D: [alsa-sink] alsa-sink.c: Requesting rewind due to latency change.
D: [alsa-sink] alsa-sink.c: Latency set to 0,50ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352704
D: [alsa-sink] alsa-sink.c: setting avail_min=88189
I: [alsa-sink] alsa-sink.c: Time scheduling watermark is 0,25ms
I: [alsa-sink] alsa-sink.c: Resumed successfully...
D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
I: [alsa-sink] alsa-sink.c: Starting playback.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
I: [alsa-sink] alsa-sink.c: Underrun!
D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
I: [alsa-sink] alsa-sink.c: Increasing minimal latency to 1,00 ms
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy.
D: [alsa-sink] alsa-sink.c: Latency set to 1,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352608
D: [alsa-sink] alsa-sink.c: setting avail_min=88177
D: [alsa-sink] alsa-sink.c: Latency set to 1,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352608
D: [alsa-sink] alsa-sink.c: setting avail_min=88177
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC0 is accessible: yes
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
I: [alsa-sink] alsa-sink.c: Increasing minimal latency to 2,00 ms
D: [alsa-sink] alsa-sink.c: Latency set to 2,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352416
D: [alsa-sink] alsa-sink.c: setting avail_min=88153
D: [alsa-sink] protocol-native.c: max_request changed, trying to update from 3936 to 4032.
D: [alsa-sink] protocol-native.c: Notifying client about increased tlength
D: [alsa-sink] alsa-sink.c: Latency set to 2,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352416
D: [alsa-sink] alsa-sink.c: setting avail_min=88153
D: [alsa-sink] ratelimit.c: 6824 events suppressed
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [pulseaudio] memblock.c: Pool full
D: [pulseaudio] memblock.c: Pool full
D: [alsa-sink] sink-input.c: Requesting rewind due to corking
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink] alsa-sink.c: setting avail_min=88009
D: [alsa-sink] alsa-sink.c: Requested volume: 0:  69% 1:  69%
D: [alsa-sink] alsa-sink.c:            in dB: 0: -9,67 dB 1: -9,67 dB
D: [alsa-sink] alsa-sink.c: Got hardware volume: 0:  69% 1:  69%
D: [alsa-sink] alsa-sink.c:               in dB: 0: -9,60 dB 1: -9,60 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,07 dB 1: -0,07 dB
D: [alsa-sink] sink.c: Volume not changing
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 128 bytes.
D: [alsa-sink] alsa-sink.c: before: 32
D: [alsa-sink] alsa-sink.c: after: 32
D: [alsa-sink] alsa-sink.c: Rewound 128 bytes.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 1315
D: [alsa-sink] source.c: Processing rewind...
D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream3
I: [pulseaudio] sink-input.c: Freeing input 3 "Event Sound"
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ...
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0004, suspending
D: [pulseaudio] ratelimit.c: 2178 events suppressed
D: [pulseaudio] flist.c: pulsecore/hashmap.c: entries flist is full (don't worry)
I: [alsa-sink] alsa-sink.c: Device suspended...
D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC0 is accessible: yes
-------------- next part --------------
I: [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-media-role:event.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream Event Sound, because already set.
I: [pulseaudio] sink-input.c: Trying to change sample rate
I: [pulseaudio] sink.c: Cannot update rate, monitor source is RUNNING
I: [pulseaudio] sink-input.c: Resampling enabled to 48000 Hz
I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:event.
I: [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-media-role:event.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy.
I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
D: [pulseaudio] resampler.c: Channel matrix:
D: [pulseaudio] resampler.c:        I00 
D: [pulseaudio] resampler.c:     +------
D: [pulseaudio] resampler.c: O00 | 1,000
D: [pulseaudio] resampler.c: O01 | 1,000
I: [pulseaudio] remap_sse.c: Using SSE mono to stereo remapping
I: [pulseaudio] resampler.c: Using resampler 'copy'
I: [pulseaudio] resampler.c: Using s16le as working format.
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 4 "Event Sound" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec s16le 1ch 48000Hz and channel map mono
I: [pulseaudio] sink-input.c:     window.icon_name = "skype"
I: [pulseaudio] sink-input.c:     application.icon_name = "skype"
I: [pulseaudio] sink-input.c:     media.role = "event"
I: [pulseaudio] sink-input.c:     media.name = "Event Sound"
I: [pulseaudio] sink-input.c:     application.name = "Skype"
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 = "2948"
I: [pulseaudio] sink-input.c:     application.process.user = "julas"
I: [pulseaudio] sink-input.c:     application.process.host = "snowball2"
I: [pulseaudio] sink-input.c:     application.process.binary = "skype"
I: [pulseaudio] sink-input.c:     application.language = "pl_PL.utf8"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "98d51596bca05b5c8d3ab7dd0000000d"
I: [pulseaudio] sink-input.c:     application.process.session_id = "98d51596bca05b5c8d3ab7dd0000000d-1355255869.676551-1599849202"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-media-role:event"
I: [pulseaudio] protocol-native.c: Requested tlength=20,02 ms, minreq=20,00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [pulseaudio] protocol-native.c: Requested latency=0,00 ms, Received latency=0,50 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=3888, base=2, prebuf=1970, minreq=1920 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=3888, base=2, prebuf=1970, minreq=1920 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 41,00 ms = 0,50 ms + 2*20,00 ms + 0,50 ms
D: [alsa-sink] alsa-sink.c: Latency set to 0,50ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352704
D: [alsa-sink] alsa-sink.c: setting avail_min=88189
D: [alsa-sink] alsa-sink.c: Requesting rewind due to latency change.
D: [alsa-sink] alsa-sink.c: Requested volume: 0:  69% 1:  69%
D: [alsa-sink] alsa-sink.c:            in dB: 0: -9,67 dB 1: -9,67 dB
D: [alsa-sink] alsa-sink.c: Got hardware volume: 0:  69% 1:  69%
D: [alsa-sink] alsa-sink.c:               in dB: 0: -9,60 dB 1: -9,60 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,07 dB 1: -0,07 dB
D: [alsa-sink] sink.c: Volume not changing
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 3264 bytes.
D: [alsa-sink] alsa-sink.c: before: 816
D: [alsa-sink] alsa-sink.c: after: 816
D: [alsa-sink] alsa-sink.c: Rewound 3264 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 1605
D: [alsa-sink] sink-input.c: Have to rewind 3264 bytes on render memblockq.
D: [alsa-sink] source.c: Processing rewind...
D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream4
I: [pulseaudio] sink.c: Cannot update rate, monitor source is RUNNING
I: [alsa-sink] ratelimit.c: 4 events suppressed
I: [alsa-sink] alsa-sink.c: Underrun!
I: [alsa-sink] alsa-sink.c: Increasing minimal latency to 1,00 ms
D: [alsa-sink] alsa-sink.c: Latency set to 1,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352608
D: [alsa-sink] alsa-sink.c: setting avail_min=88177
D: [alsa-sink] protocol-native.c: max_request changed, trying to update from 3888 to 3936.
D: [alsa-sink] protocol-native.c: Notifying client about increased tlength
D: [alsa-sink] alsa-sink.c: Latency set to 1,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352608
D: [alsa-sink] alsa-sink.c: setting avail_min=88177
D: [alsa-sink] sink-input.c: Requesting rewind due to uncorking
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Mhmm, actually there is nothing to rewind.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy.
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Mhmm, actually there is nothing to rewind.
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Mhmm, actually there is nothing to rewind.
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
D: [alsa-sink] memblock.c: Pool full
I: [alsa-sink] alsa-sink.c: Increasing minimal latency to 2,00 ms
D: [alsa-sink] alsa-sink.c: Latency set to 2,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352416
D: [alsa-sink] alsa-sink.c: setting avail_min=88153
D: [alsa-sink] protocol-native.c: max_request changed, trying to update from 3936 to 4032.
D: [alsa-sink] protocol-native.c: Notifying client about increased tlength
D: [alsa-sink] alsa-sink.c: Latency set to 2,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=352416
D: [alsa-sink] alsa-sink.c: setting avail_min=88153
D: [alsa-sink] sink-input.c: Requesting rewind due to corking
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 128 bytes.
D: [alsa-sink] alsa-sink.c: before: 32
D: [alsa-sink] alsa-sink.c: after: 32
D: [alsa-sink] alsa-sink.c: Rewound 128 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 1094
D: [alsa-sink] sink-input.c: Have to rewind 128 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 64 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] alsa-sink.c: Latency set to 20,00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=348960
D: [alsa-sink] alsa-sink.c: setting avail_min=88009
D: [alsa-sink] alsa-sink.c: Requested volume: 0:  69% 1:  69%
D: [alsa-sink] alsa-sink.c:            in dB: 0: -9,67 dB 1: -9,67 dB
D: [alsa-sink] alsa-sink.c: Got hardware volume: 0:  69% 1:  69%
D: [alsa-sink] alsa-sink.c:               in dB: 0: -9,60 dB 1: -9,60 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,07 dB 1: -0,07 dB
D: [alsa-sink] sink.c: Volume not changing
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Mhmm, actually there is nothing to rewind.
D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream4
I: [pulseaudio] sink-input.c: Freeing input 4 "Event Sound"


More information about the pulseaudio-discuss mailing list