[pulseaudio-discuss] skype sound effects create a buzzing noise

Julian Sikorski belegdol at gmail.com
Tue Jun 4 12:44:18 PDT 2013


W dniu 27.05.2013 21:56, Julian Sikorski pisze:
> W dniu 27.05.2013 05:36, Arun Raghavan pisze:
>> On Thu, 2013-05-23 at 20:26 +0200, Julian Sikorski wrote:
>>> W dniu 23.05.2013 20:10, Julian Sikorski pisze:
>>>> W dniu 23.05.2013 19:32, Arun Raghavan pisze:
>>>>> On Thu, 2013-05-23 at 19:22 +0200, Julian Sikorski wrote:
>>>>>> Hi,
>>>>>>
>>>>>> this was annoying me long enough that I have decided to investigate.
>>>>>> Basically, I am using skype-4.2 and pulseaudio-2.1 on fedora 18 x86_64.
>>>>>> Whenever skype produces a sound event (incoming message bleep, logging
>>>>>> in sound), buzzing noise will occur. This is especially prominent when
>>>>>> playing a song in the background. I have looked at the log and the error
>>>>>> message seems to be this:
>>>>>>
>>>>>> D: [alsa-sink] memblock.c: Pool full
>>>>>> D: [alsa-sink] memblock.c: Pool full
>>>>>> D: [alsa-sink] memblock.c: Pool full
>>>>>>
>>>>>> I have attached a full log. I know that PA 2.1 is really old now, but
>>>>>> Fedora seems to be having hard time aligning with PA releases these days.
>>>>>
>>>>> This happens because PulseAudio didn't handle low latency requests well
>>>>> enough. This has been fixed recently, and will be available in the next
>>>>> release (or in one of the 3.99.x prereleases if you can get a package
>>>>> for it).
>>>>>
>>>>> -- Arun
>>>>>
>>>> That's great to hear! Hopefully 4.0 will make it to Fedora 19, otherwise
>>>> it will be in Fedora 20 which is slated for a January release. It's
>>>> funny to think that Fedora was once the distro advocating pulseaudio.
>>>>
>>>> Julian
>>>>
>>> I just talked to the Fedora PA maintainer and unfortunately F-19 is too
>>> far past freeze to ship PA 4.0.
>>> Is the fix you mention possible to backport to 3.0 (which is what F-19
>>> is going to be shipping)?
>>
>> This is the one you'll want:
>>
>> http://cgit.freedesktop.org/pulseaudio/pulseaudio/commit/?id=5f326b705d8f7f0c14e7e0c7d7c2751f3a5ebe43
>>
>> Cheers,
>> Arun
>>
> Hi,
> 
> I have ported the patch over back to 2.1, but now it is even worse -
> Skype does not make any sound at all, and suppresses other system sounds
> as well:
> 
> I: [pulseaudio] client.c: Created 4 "Native client (UNIX socket client)"
> D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Client
> added for object /org/pulseaudio/core1/client4
> D: [pulseaudio] protocol-native.c: Protocol version: remote 26, local 26
> I: [pulseaudio] protocol-native.c: Got credentials: uid=500 gid=500
> success=1
> D: [pulseaudio] protocol-native.c: SHM possible: yes
> D: [pulseaudio] protocol-native.c: Negotiated SHM: yes
> D: [pulseaudio] module-augment-properties.c: Looking for .desktop file
> for skype
> D: [pulseaudio] module-augment-properties.c: Found
> /usr/share/applications/skype.desktop.
> 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 44100 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.
> 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 'speex-float-3'
> I: [pulseaudio] resampler.c: Using float32le as working format.
> I: [pulseaudio] resampler.c: Choosing speex quality setting 3.
> 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 0 "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 = "28077"
> 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-1369684384.802085-1185910046"
> I: [pulseaudio] sink-input.c:     module-stream-restore.id =
> "sink-input-by-media-role:event"
> I: [pulseaudio] protocol-native.c: Requested tlength=10,00 ms,
> minreq=2,50 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=2,50 ms, Received
> latency=2,50 ms
> D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304,
> tlength=720, base=2, prebuf=482, minreq=240 maxrewind=0
> D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304,
> tlength=720, base=2, prebuf=482, minreq=240 maxrewind=0
> I: [pulseaudio] protocol-native.c: Final latency 10,00 ms = 2,50 ms +
> 2*2,50 ms + 2,50 ms
> D: [alsa-sink] alsa-sink.c: Latency set to 2,50ms
> D: [alsa-sink] alsa-sink.c: hwbuf_unused=352360
> D: [alsa-sink] alsa-sink.c: setting avail_min=88146
> D: [alsa-sink] alsa-sink.c: Requesting rewind due to latency change.
> D: [alsa-sink] alsa-sink.c: Requested volume: 0:  76% 1:  76%
> D: [alsa-sink] alsa-sink.c:            in dB: 0: -7,05 dB 1: -7,05 dB
> D: [alsa-sink] alsa-sink.c: Got hardware volume: 0:  76% 1:  76%
> D: [alsa-sink] alsa-sink.c:               in dB: 0: -7,00 dB 1: -7,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,05 dB 1:
> -0,05 dB
> D: [alsa-sink] sink.c: Volume going down to 49997 at 39279515168
> D: [alsa-sink] ratelimit.c: 18 events suppressed
> D: [alsa-sink] sink.c: Next volume change in 11288 usec
> D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
> D: [alsa-sink] alsa-sink.c: Limited to 3176 bytes.
> D: [alsa-sink] alsa-sink.c: before: 794
> D: [alsa-sink] alsa-sink.c: after: 794
> D: [alsa-sink] alsa-sink.c: Rewound 3176 bytes.
> D: [alsa-sink] sink.c: Processing rewind...
> D: [alsa-sink] sink.c: latency = 1225
> D: [alsa-sink] sink.c: Volume change to 49997 at 39279497171 was written
> 6775 usec late
> D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream
> added for object /org/pulseaudio/core1/playback_stream0
> D: [alsa-sink] sink-input.c: Have to rewind 3176 bytes on render memblockq.
> D: [alsa-sink] source.c: Processing rewind...
> I: [pulseaudio] sink.c: Cannot update rate, monitor source is RUNNING
> 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: Limited to 120 bytes.
> 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: before: 30
> D: [alsa-sink] alsa-sink.c: after: 30
> D: [alsa-sink] alsa-sink.c: Rewound 120 bytes.
> D: [alsa-sink] sink.c: Processing rewind...
> D: [alsa-sink] sink.c: latency = 1422
> D: [alsa-sink] source.c: Processing 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: Limited to 56 bytes.
> D: [alsa-sink] alsa-sink.c: before: 14
> D: [alsa-sink] alsa-sink.c: after: 14
> D: [alsa-sink] alsa-sink.c: Rewound 56 bytes.
> D: [alsa-sink] sink.c: Processing rewind...
> D: [alsa-sink] sink.c: latency = 1410
> D: [alsa-sink] sink-input.c: Have to rewind 56 bytes on render memblockq.
> D: [alsa-sink] source.c: Processing rewind...
> D: [alsa-sink] memblock.c: Pool full
> D: [pulseaudio] 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: [alsa-source] memblock.c: Pool full
> D: [alsa-source] memblock.c: Pool full
> D: [alsa-sink] memblock.c: Pool full
> I: [alsa-sink] alsa-sink.c: Increasing minimal latency to 1,00 ms
> D: [alsa-sink] alsa-sink.c: Latency set to 2,50ms
> D: [alsa-sink] alsa-sink.c: hwbuf_unused=352360
> D: [alsa-sink] alsa-sink.c: setting avail_min=88146
> D: [alsa-sink] alsa-sink.c: Latency set to 2,50ms
> D: [alsa-sink] alsa-sink.c: hwbuf_unused=352360
> D: [alsa-sink] alsa-sink.c: setting avail_min=88146
> D: [alsa-sink] protocol-native.c: Underrun on 'Event Sound', 0 bytes in
> queue.
> D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
> D: [alsa-sink] alsa-sink.c: Requested to rewind 52 bytes.
> D: [alsa-sink] alsa-sink.c: Limited to 52 bytes.
> D: [alsa-sink] alsa-sink.c: before: 13
> D: [alsa-sink] alsa-sink.c: after: 13
> D: [alsa-sink] alsa-sink.c: Rewound 52 bytes.
> D: [alsa-sink] sink.c: Processing rewind...
> D: [alsa-sink] sink.c: latency = 1181
> D: [alsa-sink] sink-input.c: Have to rewind 52 bytes on render memblockq.
> D: [alsa-sink] source.c: Processing rewind...
> D: [alsa-sink] ratelimit.c: 9756 events suppressed
> D: [alsa-sink] memblock.c: Pool full
> D: [alsa-sink] memblock.c: Pool full
> D: [pulseaudio] 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
> I: [pulseaudio] source-output.c: Trying to change sample rate
> I: [pulseaudio] source.c: Cannot update rate, SOURCE_IS_RUNNING, will
> keep using 44100 Hz
> I: [pulseaudio] source-output.c: Resampling enabled to 44100 Hz
> I: [pulseaudio] module-stream-restore.c: Restoring volume for source
> output source-output-by-media-role:phone.
> I: [pulseaudio] module-stream-restore.c: Restoring mute state for source
> output source-output-by-media-role:phone.
> D: [pulseaudio] module-suspend-on-idle.c: Source
> alsa_input.pci-0000_00_1b.0.analog-stereo becomes busy.
> D: [pulseaudio] resampler.c: Channel matrix:
> D: [pulseaudio] resampler.c:        I00   I01
> D: [pulseaudio] resampler.c:     +------------
> D: [pulseaudio] resampler.c: O00 | 1,000 1,000
> I: [pulseaudio] remap.c: Using generic matrix remapping
> I: [pulseaudio] resampler.c: Using resampler 'speex-float-3'
> I: [pulseaudio] resampler.c: Using float32le as working format.
> I: [pulseaudio] resampler.c: Choosing speex quality setting 3.
> 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] source-output.c: Created output 3 "Input" on
> alsa_input.pci-0000_00_1b.0.analog-stereo with sample spec s16le 1ch
> 48000Hz and channel map mono
> I: [pulseaudio] source-output.c:     window.icon_name = "skype"
> I: [pulseaudio] source-output.c:     application.icon_name = "skype"
> I: [pulseaudio] source-output.c:     media.role = "phone"
> I: [pulseaudio] source-output.c:     media.name = "Input"
> I: [pulseaudio] source-output.c:     application.name = "Skype"
> I: [pulseaudio] source-output.c:     native-protocol.peer = "UNIX socket
> client"
> I: [pulseaudio] source-output.c:     native-protocol.version = "26"
> I: [pulseaudio] source-output.c:     application.process.id = "28077"
> I: [pulseaudio] source-output.c:     application.process.user = "julas"
> I: [pulseaudio] source-output.c:     application.process.host = "snowball2"
> I: [pulseaudio] source-output.c:     application.process.binary = "skype"
> I: [pulseaudio] source-output.c:     application.language = "pl_PL.utf8"
> I: [pulseaudio] source-output.c:     window.x11.display = ":0"
> I: [pulseaudio] source-output.c:     application.process.machine_id =
> "98d51596bca05b5c8d3ab7dd0000000d"
> I: [pulseaudio] source-output.c:     application.process.session_id =
> "98d51596bca05b5c8d3ab7dd0000000d-1369684384.802085-1185910046"
> I: [pulseaudio] source-output.c:     module-stream-restore.id =
> "source-output-by-media-role:phone"
> D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304,
> tlength=0, base=2, prebuf=1, minreq=0 maxrewind=0
> D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304,
> tlength=4194304, base=2, prebuf=2, minreq=2 maxrewind=0
> I: [pulseaudio] protocol-native.c: Final latency 10,00 ms = 5,00 ms +
> 5,00 ms
> D: [alsa-source] alsa-source.c: latency set to 5,00ms
> D: [alsa-source] alsa-source.c: hwbuf_unused=351920
> D: [alsa-source] alsa-source.c: setting avail_min=111
> D: [alsa-source] alsa-source.c: latency set to 5,00ms
> D: [alsa-source] alsa-source.c: hwbuf_unused=351920
> D: [alsa-source] alsa-source.c: setting avail_min=111
> D: [alsa-source] alsa-source.c: Requested volume: 0:   0% 1:   0%
> D: [alsa-source] alsa-source.c:            in dB: 0: -inf dB 1: -inf dB
> D: [alsa-source] alsa-source.c: Got hardware volume: 0:   5% 1:   5%
> D: [alsa-source] alsa-source.c:               in dB: 0: -76,00 dB 1:
> -76,00 dB
> D: [alsa-source] alsa-source.c: Calculated software volume: 0:   0% 1:
>  0% (accurate-enough=no)
> D: [alsa-source] alsa-source.c:                      in dB: 0: -inf dB
> 1: -inf dB
> D: [alsa-source] source.c: Volume not changing
> D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream
> added for object /org/pulseaudio/core1/record_stream3
> D: [alsa-sink] protocol-native.c: Underrun on 'Event Sound', 0 bytes in
> queue.
> D: [pulseaudio] module-suspend-on-idle.c: Source
> alsa_input.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 500 bytes.
> D: [alsa-sink] alsa-sink.c: Mhmm, actually there is nothing to rewind.
> I: [alsa-sink] alsa-sink.c: Increasing minimal latency to 2,00 ms
> D: [alsa-sink] alsa-sink.c: Latency set to 2,50ms
> D: [alsa-sink] alsa-sink.c: hwbuf_unused=352360
> D: [alsa-sink] alsa-sink.c: setting avail_min=88146
> D: [alsa-sink] alsa-sink.c: Latency set to 2,50ms
> D: [alsa-sink] alsa-sink.c: hwbuf_unused=352360
> D: [alsa-sink] alsa-sink.c: setting avail_min=88146
> D: [pulseaudio] protocol-native.c: Client skype changes volume of source
> alsa_input.pci-0000_00_1b.0.analog-stereo.
> D: [alsa-source] alsa-source.c: Requested volume: 0:   0% 1:   0%
> D: [alsa-source] alsa-source.c:            in dB: 0: -inf dB 1: -inf dB
> D: [alsa-source] alsa-source.c: Got hardware volume: 0:   5% 1:   5%
> D: [alsa-source] alsa-source.c:               in dB: 0: -76,00 dB 1:
> -76,00 dB
> D: [alsa-source] alsa-source.c: Calculated software volume: 0:   0% 1:
>  0% (accurate-enough=no)
> D: [alsa-source] alsa-source.c:                      in dB: 0: -inf dB
> 1: -inf dB
> D: [alsa-source] source.c: Volume not changing
> D: [alsa-sink] protocol-native.c: Underrun on 'Event Sound', 0 bytes in
> queue.
> D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
> D: [alsa-sink] alsa-sink.c: Requested to rewind 92 bytes.
> D: [alsa-sink] alsa-sink.c: Limited to 88 bytes.
> D: [alsa-sink] alsa-sink.c: before: 22
> D: [alsa-sink] alsa-sink.c: after: 22
> D: [alsa-sink] alsa-sink.c: Rewound 88 bytes.
> D: [alsa-sink] sink.c: Processing rewind...
> D: [alsa-sink] sink.c: latency = 877
> D: [alsa-sink] sink-input.c: Have to rewind 88 bytes on render memblockq.
> D: [alsa-sink] source.c: Processing rewind...
> D: [alsa-source] alsa-source.c: latency set to 20,00ms
> D: [alsa-source] alsa-source.c: hwbuf_unused=349272
> D: [alsa-source] alsa-source.c: setting avail_min=707
> D: [alsa-source] alsa-source.c: Requested volume: 0:   0% 1:   0%
> D: [alsa-source] alsa-source.c:            in dB: 0: -inf dB 1: -inf dB
> D: [alsa-source] alsa-source.c: Got hardware volume: 0:   5% 1:   5%
> D: [alsa-source] alsa-source.c:               in dB: 0: -76,00 dB 1:
> -76,00 dB
> D: [alsa-source] alsa-source.c: Calculated software volume: 0:   0% 1:
>  0% (accurate-enough=no)
> D: [alsa-source] alsa-source.c:                      in dB: 0: -inf dB
> 1: -inf dB
> D: [alsa-source] source.c: Volume not changing
> D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream
> removed from object /org/pulseaudio/core1/record_stream3
> I: [pulseaudio] source-output.c: Freeing output 3 "Input"
> 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, SINK_IS_RUNNING, will keep
> using 44100 Hz
> I: [pulseaudio] sink-input.c: Resampling enabled to 44100 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.
> 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 'speex-float-3'
> I: [pulseaudio] resampler.c: Using float32le as working format.
> I: [pulseaudio] resampler.c: Choosing speex quality setting 3.
> 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 1 "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 = "28077"
> 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-1369684384.802085-1185910046"
> I: [pulseaudio] sink-input.c:     module-stream-restore.id =
> "sink-input-by-media-role:event"
> I: [pulseaudio] protocol-native.c: Requested tlength=10,00 ms,
> minreq=2,50 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=2,50 ms, Received
> latency=2,50 ms
> D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304,
> tlength=720, base=2, prebuf=482, minreq=240 maxrewind=0
> D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304,
> tlength=720, base=2, prebuf=482, minreq=240 maxrewind=0
> I: [pulseaudio] protocol-native.c: Final latency 10,00 ms = 2,50 ms +
> 2*2,50 ms + 2,50 ms
> D: [alsa-sink] alsa-sink.c: Latency set to 2,50ms
> D: [alsa-sink] alsa-sink.c: hwbuf_unused=352360
> D: [alsa-sink] alsa-sink.c: setting avail_min=88146
> D: [alsa-sink] alsa-sink.c: Requested volume: 0:  76% 1:  76%
> D: [alsa-sink] alsa-sink.c:            in dB: 0: -7,05 dB 1: -7,05 dB
> D: [alsa-sink] alsa-sink.c: Got hardware volume: 0:  76% 1:  76%
> D: [alsa-sink] alsa-sink.c:               in dB: 0: -7,00 dB 1: -7,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,05 dB 1:
> -0,05 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_stream1
> D: [alsa-sink] protocol-native.c: Underrun on 'Event Sound', 0 bytes in
> queue.
> D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
> D: [alsa-sink] alsa-sink.c: Requested to rewind 216 bytes.
> D: [alsa-sink] alsa-sink.c: Limited to 184 bytes.
> D: [alsa-sink] alsa-sink.c: before: 46
> D: [alsa-sink] alsa-sink.c: after: 46
> D: [alsa-sink] alsa-sink.c: Rewound 184 bytes.
> D: [alsa-sink] sink.c: Processing rewind...
> D: [alsa-sink] sink.c: latency = 1348
> D: [alsa-sink] sink-input.c: Have to rewind 184 bytes on render memblockq.
> D: [alsa-sink] sink-input.c: Have to rewind 184 bytes on render memblockq.
> D: [alsa-sink] source.c: Processing rewind...
> D: [alsa-sink] sink-input.c: Requesting rewind due to uncorking
> D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
> 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: Limited to 120 bytes.
> D: [alsa-sink] alsa-sink.c: before: 30
> D: [alsa-sink] alsa-sink.c: after: 30
> D: [alsa-sink] alsa-sink.c: Rewound 120 bytes.
> D: [alsa-sink] sink.c: Processing rewind...
> D: [alsa-sink] sink.c: latency = 1376
> D: [alsa-sink] sink-input.c: Have to rewind 120 bytes on render memblockq.
> D: [alsa-sink] source.c: Processing 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: Limited to 88 bytes.
> D: [alsa-sink] alsa-sink.c: before: 22
> D: [alsa-sink] alsa-sink.c: after: 22
> D: [alsa-sink] alsa-sink.c: Rewound 88 bytes.
> D: [alsa-sink] sink.c: Processing rewind...
> D: [alsa-sink] sink.c: latency = 1265
> D: [alsa-sink] sink-input.c: Have to rewind 88 bytes on render memblockq.
> D: [alsa-sink] sink-input.c: Have to rewind 88 bytes on render memblockq.
> D: [alsa-sink] source.c: Processing rewind...
> D: [alsa-sink] sink-input.c: Requesting rewind due to corking
> D: [alsa-sink] alsa-sink.c: Requested to rewind 216 bytes.
> D: [alsa-sink] alsa-sink.c: Limited to 152 bytes.
> D: [alsa-sink] alsa-sink.c: before: 38
> D: [alsa-sink] alsa-sink.c: after: 38
> D: [alsa-sink] alsa-sink.c: Rewound 152 bytes.
> D: [alsa-sink] sink.c: Processing rewind...
> D: [alsa-sink] sink.c: latency = 1290
> D: [alsa-sink] sink-input.c: Have to rewind 152 bytes on render memblockq.
> D: [alsa-sink] sink-input.c: Have to rewind 152 bytes on render memblockq.
> D: [alsa-sink] sink-input.c: Have to rewind 84 bytes on implementor.
> D: [alsa-sink] source.c: Processing rewind...
> D: [alsa-sink] alsa-sink.c: Latency set to 2,50ms
> D: [alsa-sink] alsa-sink.c: hwbuf_unused=352360
> D: [alsa-sink] alsa-sink.c: setting avail_min=88146
> D: [alsa-sink] alsa-sink.c: Requested volume: 0:  76% 1:  76%
> D: [alsa-sink] alsa-sink.c:            in dB: 0: -7,05 dB 1: -7,05 dB
> D: [alsa-sink] alsa-sink.c: Got hardware volume: 0:  76% 1:  76%
> D: [alsa-sink] alsa-sink.c:               in dB: 0: -7,00 dB 1: -7,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,05 dB 1:
> -0,05 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 88 bytes.
> D: [alsa-sink] alsa-sink.c: before: 22
> D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream
> removed from object /org/pulseaudio/core1/playback_stream1
> D: [alsa-sink] alsa-sink.c: after: 22
> I: [pulseaudio] sink-input.c: Freeing input 1 "Event Sound"
> D: [alsa-sink] alsa-sink.c: Rewound 88 bytes.
> D: [alsa-sink] sink.c: Processing rewind...
> D: [alsa-sink] sink.c: latency = 1167
> D: [alsa-sink] sink-input.c: Have to rewind 88 bytes on render memblockq.
> D: [alsa-sink] source.c: Processing rewind...
> I: [alsa-sink] alsa-sink.c: Increasing minimal latency to 4,00 ms
> D: [alsa-sink] alsa-sink.c: Latency set to 4,00ms
> D: [alsa-sink] alsa-sink.c: hwbuf_unused=352096
> D: [alsa-sink] alsa-sink.c: setting avail_min=88113
> D: [alsa-sink] protocol-native.c: max_request changed, trying to update
> from 720 to 864.
> D: [alsa-sink] protocol-native.c: Notifying client about increased tlength
> D: [alsa-sink] alsa-sink.c: Latency set to 4,00ms
> D: [alsa-sink] alsa-sink.c: hwbuf_unused=352096
> D: [alsa-sink] alsa-sink.c: setting avail_min=88113
> D: [alsa-sink] protocol-native.c: Underrun on 'Event Sound', 0 bytes in
> queue.
> D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
> D: [alsa-sink] alsa-sink.c: Requested to rewind 96 bytes.
> D: [alsa-sink] alsa-sink.c: Limited to 96 bytes.
> D: [alsa-sink] alsa-sink.c: before: 24
> D: [alsa-sink] alsa-sink.c: after: 24
> D: [alsa-sink] alsa-sink.c: Rewound 96 bytes.
> D: [alsa-sink] sink.c: Processing rewind...
> D: [alsa-sink] sink.c: latency = 2550
> D: [alsa-sink] sink-input.c: Have to rewind 96 bytes on render memblockq.
> D: [alsa-sink] source.c: Processing rewind...
> D: [alsa-source] ratelimit.c: 12173 events suppressed
> D: [alsa-source] memblock.c: Pool full
> D: [alsa-source] memblock.c: Pool full
> D: [alsa-source] 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: [alsa-sink] memblock.c: Pool full
> D: [pulseaudio] memblock.c: Pool full
> D: [alsa-sink] memblock.c: Pool full
> D: [alsa-sink] memblock.c: Pool full
> D: [alsa-sink] ratelimit.c: 5445 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: [pulseaudio] 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: [alsa-sink] memblock.c: Pool full
> 
> 
> Best regards,
> Julian
> 
I tried adding the previous commit as well:

http://cgit.freedesktop.org/pulseaudio/pulseaudio/commit/src/pulsecore/protocol-native.c?id=2026c41be6f4e1a526c94c1368e5e528597df03a

Unfortunately, still no dice. I have then tried to compare both logs
(attached). The only obvious thing coming up was

D: [alsa-sink] protocol-native.c: Underrun on 'Event Sound', 0 bytes in
queue.

Are there more patches required to make this one work? Thank you for
your advice in advance.

Best regards,
Julian
-------------- next part --------------
A non-text attachment was scrubbed...
Name: pa-patched.log.xz
Type: application/x-xz
Size: 13720 bytes
Desc: not available
URL: <http://lists.freedesktop.org/archives/pulseaudio-discuss/attachments/20130604/92a2f6bd/attachment-0002.bin>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: pa-std.log.xz
Type: application/x-xz
Size: 13840 bytes
Desc: not available
URL: <http://lists.freedesktop.org/archives/pulseaudio-discuss/attachments/20130604/92a2f6bd/attachment-0003.bin>


More information about the pulseaudio-discuss mailing list